builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0247 starttime: 1469018981.79 results: success (0) buildid: 20160720030208 builduid: 86fb8206fd4840c2b269262cad0d458f revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.793886) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.794275) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.794541) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.819098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.819449) ========= 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.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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-20 05:49:41-- 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% 19.2M=0.001s 2016-07-20 05:49:41 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.935302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.935612) ========= 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.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.025992 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:41.983521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 05:49:41.983873) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ed8e23b5e0c7b739e61173bb180cf3410a306679 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ed8e23b5e0c7b739e61173bb180cf3410a306679 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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-20 05:49:42,066 truncating revision to first 12 chars 2016-07-20 05:49:42,066 Setting DEBUG logging. 2016-07-20 05:49:42,066 attempt 1/10 2016-07-20 05:49:42,066 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ed8e23b5e0c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 05:49:42,778 unpacking tar archive at: mozilla-central-ed8e23b5e0c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.988717 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 05:49:42.994071) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:42.994385) ========= script_repo_revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:42.994748) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:42.995021) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:49:43.013633) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-20 05:49:43.014006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:49:43 INFO - MultiFileLogger online at 20160720 05:49:43 in /builds/slave/test 05:49:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:49:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:49:43 INFO - {'append_to_log': False, 05:49:43 INFO - 'base_work_dir': '/builds/slave/test', 05:49:43 INFO - 'blob_upload_branch': 'Firefox', 05:49:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:49:43 INFO - 'branch': 'Firefox', 05:49:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:49:43 INFO - 'config_files': ('talos/mac_config.py',), 05:49:43 INFO - 'default_actions': ('clobber', 05:49:43 INFO - 'read-buildbot-config', 05:49:43 INFO - 'download-and-extract', 05:49:43 INFO - 'populate-webroot', 05:49:43 INFO - 'create-virtualenv', 05:49:43 INFO - 'install', 05:49:43 INFO - 'run-tests'), 05:49:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:49:43 INFO - 'download_minidump_stackwalk': True, 05:49:43 INFO - 'download_symbols': 'ondemand', 05:49:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:49:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:49:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:49:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:49:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:49:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:49:43 INFO - 'installer_path': 'installer.exe', 05:49:43 INFO - 'log_level': 'info', 05:49:43 INFO - 'log_name': 'talos', 05:49:43 INFO - 'log_to_console': True, 05:49:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:49:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:49:43 INFO - 'opt_config_files': (), 05:49:43 INFO - 'pip_index': False, 05:49:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:49:43 INFO - 'cmd': ('bash', 05:49:43 INFO - '-c', 05:49:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:49:43 INFO - 'enabled': True, 05:49:43 INFO - 'halt_on_failure': False, 05:49:43 INFO - 'name': 'check_screen_resolution'},), 05:49:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:49:43 INFO - 'cmd': ('bash', 05:49:43 INFO - '-c', 05:49:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:49:43 INFO - 'enabled': True, 05:49:43 INFO - 'halt_on_failure': False, 05:49:43 INFO - 'name': 'check_screen_resolution'},), 05:49:43 INFO - 'run_cmd_checks_enabled': True, 05:49:43 INFO - 'sps_profile': False, 05:49:43 INFO - 'sps_profile_interval': 0, 05:49:43 INFO - 'suite': 'dromaeojs', 05:49:43 INFO - 'system_bits': '32', 05:49:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:49:43 INFO - 'title': 't-yosemite-r7-0247', 05:49:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:49:43 INFO - 'use_talos_json': True, 05:49:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:49:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:49:43 INFO - 'work_dir': 'build'} 05:49:43 INFO - [mozharness: 2016-07-20 12:49:43.208424Z] Running clobber step. 05:49:43 INFO - Running pre-action listener: _resource_record_pre_action 05:49:43 INFO - Running main action method: clobber 05:49:43 INFO - rmtree: /builds/slave/test/build 05:49:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:49:45 INFO - Running post-action listener: _resource_record_post_action 05:49:45 INFO - [mozharness: 2016-07-20 12:49:45.234313Z] Finished clobber step (success) 05:49:45 INFO - [mozharness: 2016-07-20 12:49:45.234533Z] Running read-buildbot-config step. 05:49:45 INFO - Running pre-action listener: _resource_record_pre_action 05:49:45 INFO - Running main action method: read_buildbot_config 05:49:45 INFO - Using buildbot properties: 05:49:45 INFO - { 05:49:45 INFO - "project": "", 05:49:45 INFO - "product": "firefox", 05:49:45 INFO - "script_repo_revision": "production", 05:49:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:49:45 INFO - "repository": "", 05:49:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:49:45 INFO - "buildid": "20160720030208", 05:49:45 INFO - "stage_platform": "macosx64", 05:49:45 INFO - "basedir": "/builds/slave/test", 05:49:45 INFO - "nightly_build": "True", 05:49:45 INFO - "buildnumber": 303, 05:49:45 INFO - "slavename": "t-yosemite-r7-0247", 05:49:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:49:45 INFO - "platform": "yosemite_r7", 05:49:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:49:45 INFO - "branch": "mozilla-central", 05:49:45 INFO - "repo_path": "mozilla-central", 05:49:45 INFO - "revision": "ed8e23b5e0c7b739e61173bb180cf3410a306679", 05:49:45 INFO - "pgo_build": "False", 05:49:45 INFO - "builduid": "86fb8206fd4840c2b269262cad0d458f", 05:49:45 INFO - "slavebuilddir": "test" 05:49:45 INFO - } 05:49:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:49:45 INFO - Running post-action listener: _resource_record_post_action 05:49:45 INFO - [mozharness: 2016-07-20 12:49:45.240430Z] Finished read-buildbot-config step (success) 05:49:45 INFO - [mozharness: 2016-07-20 12:49:45.240646Z] Running download-and-extract step. 05:49:45 INFO - Running pre-action listener: _resource_record_pre_action 05:49:45 INFO - Running main action method: download_and_extract 05:49:45 INFO - mkdir: /builds/slave/test/build/tests 05:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:45 INFO - https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:49:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:49:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:49:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:46 INFO - Downloaded 1199 bytes. 05:49:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:49:46 INFO - Using the following test package requirements: 05:49:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:49:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:49:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:49:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'jsshell-mac.zip'], 05:49:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:49:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:49:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:49:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:49:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:49:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:49:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:49:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:46 INFO - https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:49:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:49:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:49:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:48 INFO - Downloaded 18842884 bytes. 05:49:48 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'] 05:49:48 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 05:49:51 INFO - Return code: 0 05:49:51 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 05:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:51 INFO - https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:49:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:49:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:49:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:53 INFO - Downloaded 18842884 bytes. 05:49:53 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'] 05:49:53 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 05:49:56 INFO - Return code: 0 05:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:56 INFO - https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:49:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:49:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:49:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:49:57 INFO - Downloaded 14403317 bytes. 05:49:57 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'] 05:49:57 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 05:49:57 INFO - Return code: 0 05:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:57 INFO - https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:49:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:49:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:49:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/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 05:50:01 INFO - Downloaded 113061967 bytes. 05:50:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:50:01 INFO - mkdir: /builds/slave/test/properties 05:50:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:50:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:50:01 INFO - Contents: 05:50:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:50:01 INFO - Running post-action listener: _resource_record_post_action 05:50:01 INFO - Running post-action listener: set_extra_try_arguments 05:50:01 INFO - [mozharness: 2016-07-20 12:50:01.834791Z] Finished download-and-extract step (success) 05:50:01 INFO - [mozharness: 2016-07-20 12:50:01.834903Z] Running populate-webroot step. 05:50:01 INFO - Running pre-action listener: _resource_record_pre_action 05:50:01 INFO - Running main action method: populate_webroot 05:50:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:50:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:50:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:50:01 INFO - '--tppagecycles', 05:50:01 INFO - '7'], 05:50:01 INFO - 'tests': ['tsvgm']}}, 05:50:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:50:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:50:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:50:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:50:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:50:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:50:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['damp', 'tps']}, 05:50:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'tests': ['damp', 'tps']}, 05:50:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['dromaeo_dom']}, 05:50:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:50:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['basic_compositor_video']}, 05:50:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:50:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['a11yr', 05:50:01 INFO - 'ts_paint', 05:50:01 INFO - 'tpaint', 05:50:01 INFO - 'sessionrestore', 05:50:01 INFO - 'sessionrestore_no_auto_restore', 05:50:01 INFO - 'tabpaint']}, 05:50:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['tsvgx', 05:50:01 INFO - 'tsvgr_opacity', 05:50:01 INFO - 'tart', 05:50:01 INFO - 'tscrollx', 05:50:01 INFO - 'cart']}, 05:50:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:50:01 INFO - 'tsvgr_opacity', 05:50:01 INFO - 'tart', 05:50:01 INFO - 'tscrollx', 05:50:01 INFO - 'cart']}, 05:50:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'talos_options': ['--disable-e10s'], 05:50:01 INFO - 'tests': ['tp5o']}, 05:50:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'tests': ['tp5o']}, 05:50:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'talos_options': ['--disable-e10s', 05:50:01 INFO - '--xperf_path', 05:50:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:50:01 INFO - 'tests': ['tp5n']}, 05:50:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:50:01 INFO - 'talos_options': ['--xperf_path', 05:50:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:50:01 INFO - 'tests': ['tp5n']}}, 05:50:01 INFO - 'talos.zip': {'path': '', 05:50:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:50:01 INFO - Running post-action listener: _resource_record_post_action 05:50:01 INFO - [mozharness: 2016-07-20 12:50:01.854028Z] Finished populate-webroot step (success) 05:50:01 INFO - [mozharness: 2016-07-20 12:50:01.854136Z] Running create-virtualenv step. 05:50:01 INFO - Running pre-action listener: _resource_record_pre_action 05:50:01 INFO - Running main action method: create_virtualenv 05:50:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:50:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:50:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:50:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:50:01 INFO - Using real prefix '/tools/python27' 05:50:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:50:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:50:05 INFO - Installing pip.................done. 05:50:05 INFO - Return code: 0 05:50:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:50: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')]} 05:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50: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')]} 05:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:50:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:05 INFO - 'HOME': '/Users/cltbld', 05:50:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:05 INFO - 'LOGNAME': 'cltbld', 05:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:05 INFO - 'MOZ_NO_REMOTE': '1', 05:50:05 INFO - 'NO_EM_RESTART': '1', 05:50:05 INFO - 'PAGER': '/bin/cat', 05:50:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:05 INFO - 'PWD': '/builds/slave/test', 05:50:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:05 INFO - 'SHELL': '/bin/bash', 05:50:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:05 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:05 INFO - 'USER': 'cltbld', 05:50:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:05 INFO - 'XPC_FLAGS': '0x0', 05:50:05 INFO - 'XPC_SERVICE_NAME': '0', 05:50:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:50:05 INFO - Cleaning up... 05:50:05 INFO - Return code: 0 05:50:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:50: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')]} 05:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50: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')]} 05:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:50:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:05 INFO - 'HOME': '/Users/cltbld', 05:50:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:05 INFO - 'LOGNAME': 'cltbld', 05:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:05 INFO - 'MOZ_NO_REMOTE': '1', 05:50:05 INFO - 'NO_EM_RESTART': '1', 05:50:05 INFO - 'PAGER': '/bin/cat', 05:50:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:05 INFO - 'PWD': '/builds/slave/test', 05:50:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:05 INFO - 'SHELL': '/bin/bash', 05:50:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:05 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:05 INFO - 'USER': 'cltbld', 05:50:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:05 INFO - 'XPC_FLAGS': '0x0', 05:50:05 INFO - 'XPC_SERVICE_NAME': '0', 05:50:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:06 INFO - Downloading/unpacking psutil>=3.1.1 05:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:50:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:50:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:50:09 INFO - Installing collected packages: psutil 05:50:09 INFO - Running setup.py install for psutil 05:50:09 INFO - building 'psutil._psutil_osx' extension 05:50:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:50:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:50:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:50:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:50:09 INFO - building 'psutil._psutil_posix' extension 05:50:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:50:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:50:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:50:10 INFO - ^ 05:50:10 INFO - 1 warning generated. 05:50:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:50:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:50:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:50:10 INFO - Successfully installed psutil 05:50:10 INFO - Cleaning up... 05:50:10 INFO - Return code: 0 05:50:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:10 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 05:50:10 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 05:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:10 INFO - 'HOME': '/Users/cltbld', 05:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:10 INFO - 'LOGNAME': 'cltbld', 05:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:10 INFO - 'MOZ_NO_REMOTE': '1', 05:50:10 INFO - 'NO_EM_RESTART': '1', 05:50:10 INFO - 'PAGER': '/bin/cat', 05:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:10 INFO - 'PWD': '/builds/slave/test', 05:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:10 INFO - 'SHELL': '/bin/bash', 05:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:10 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:10 INFO - 'USER': 'cltbld', 05:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:10 INFO - 'XPC_FLAGS': '0x0', 05:50:10 INFO - 'XPC_SERVICE_NAME': '0', 05:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:50:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:50:13 INFO - Installing collected packages: mozsystemmonitor 05:50:13 INFO - Running setup.py install for mozsystemmonitor 05:50:13 INFO - Successfully installed mozsystemmonitor 05:50:13 INFO - Cleaning up... 05:50:13 INFO - Return code: 0 05:50:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:13 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:13 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 05:50:13 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 05:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:13 INFO - 'HOME': '/Users/cltbld', 05:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:13 INFO - 'LOGNAME': 'cltbld', 05:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:13 INFO - 'MOZ_NO_REMOTE': '1', 05:50:13 INFO - 'NO_EM_RESTART': '1', 05:50:13 INFO - 'PAGER': '/bin/cat', 05:50:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:13 INFO - 'PWD': '/builds/slave/test', 05:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:13 INFO - 'SHELL': '/bin/bash', 05:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:13 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:13 INFO - 'USER': 'cltbld', 05:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:13 INFO - 'XPC_FLAGS': '0x0', 05:50:13 INFO - 'XPC_SERVICE_NAME': '0', 05:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:13 INFO - Downloading/unpacking jsonschema==2.5.1 05:50: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 05:50: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 05:50: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 05:50: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 05:50: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 05:50: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 05:50:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:50:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:50: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 05:50: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 05:50: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 05:50: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 05:50: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 05:50: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 05:50:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:50:16 INFO - warning: no files found matching '*.txt' 05:50:16 INFO - no previously-included directories found matching 'build' 05:50:16 INFO - no previously-included directories found matching 'dist' 05:50:16 INFO - no previously-included directories found matching '.git*' 05:50:16 INFO - Installing collected packages: jsonschema, functools32 05:50:16 INFO - Running setup.py install for functools32 05:50:16 INFO - warning: no files found matching '*.txt' 05:50:16 INFO - no previously-included directories found matching 'build' 05:50:16 INFO - no previously-included directories found matching 'dist' 05:50:16 INFO - no previously-included directories found matching '.git*' 05:50:16 INFO - Successfully installed jsonschema functools32 05:50:16 INFO - Cleaning up... 05:50:16 INFO - Return code: 0 05:50:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:16 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:16 INFO - 'HOME': '/Users/cltbld', 05:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:16 INFO - 'LOGNAME': 'cltbld', 05:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:16 INFO - 'MOZ_NO_REMOTE': '1', 05:50:16 INFO - 'NO_EM_RESTART': '1', 05:50:16 INFO - 'PAGER': '/bin/cat', 05:50:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:16 INFO - 'PWD': '/builds/slave/test', 05:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:16 INFO - 'SHELL': '/bin/bash', 05:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:16 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:16 INFO - 'USER': 'cltbld', 05:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:16 INFO - 'XPC_FLAGS': '0x0', 05:50:16 INFO - 'XPC_SERVICE_NAME': '0', 05:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:19 INFO - Downloading blobuploader-1.2.4.tar.gz 05:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:50:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:50:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:20 INFO - Downloading docopt-0.6.1.tar.gz 05:50:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:50:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:50:20 INFO - Running setup.py install for blobuploader 05:50:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:50:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:50:20 INFO - Running setup.py install for requests 05:50:21 INFO - Running setup.py install for docopt 05:50:21 INFO - Successfully installed blobuploader requests docopt 05:50:21 INFO - Cleaning up... 05:50:21 INFO - Return code: 0 05:50:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:21 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:21 INFO - 'HOME': '/Users/cltbld', 05:50:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:21 INFO - 'LOGNAME': 'cltbld', 05:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:21 INFO - 'MOZ_NO_REMOTE': '1', 05:50:21 INFO - 'NO_EM_RESTART': '1', 05:50:21 INFO - 'PAGER': '/bin/cat', 05:50:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:21 INFO - 'PWD': '/builds/slave/test', 05:50:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:21 INFO - 'SHELL': '/bin/bash', 05:50:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:21 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:21 INFO - 'USER': 'cltbld', 05:50:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:21 INFO - 'XPC_FLAGS': '0x0', 05:50:21 INFO - 'XPC_SERVICE_NAME': '0', 05:50:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:50:21 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-42S2bK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Ipw5u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-d1dbba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-3uVMvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-sYZrOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-qoaBhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-fmNz7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-rFEINC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-5j2G70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-8qi5F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:50:22 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-_JLDdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-AQ0gMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-lAjsBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-qeroxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-hKHDnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-mrmDvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:50:23 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-6xtVlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:50:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:50:23 INFO - Running setup.py install for manifestparser 05:50:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:50:23 INFO - Running setup.py install for mozcrash 05:50:23 INFO - Running setup.py install for mozdebug 05:50:24 INFO - Running setup.py install for mozdevice 05:50:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Running setup.py install for mozfile 05:50:24 INFO - Running setup.py install for mozhttpd 05:50:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Running setup.py install for mozinfo 05:50:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Running setup.py install for mozInstall 05:50:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:50:24 INFO - Running setup.py install for mozleak 05:50:24 INFO - Running setup.py install for mozlog 05:50:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Running setup.py install for moznetwork 05:50:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Running setup.py install for mozprocess 05:50:25 INFO - Running setup.py install for mozprofile 05:50:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Running setup.py install for mozrunner 05:50:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:50:25 INFO - Running setup.py install for mozscreenshot 05:50:25 INFO - Running setup.py install for moztest 05:50:26 INFO - Running setup.py install for mozversion 05:50:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:50:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:50:26 INFO - Cleaning up... 05:50:26 INFO - Return code: 0 05:50:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:26 INFO - 'HOME': '/Users/cltbld', 05:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:26 INFO - 'LOGNAME': 'cltbld', 05:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:26 INFO - 'MOZ_NO_REMOTE': '1', 05:50:26 INFO - 'NO_EM_RESTART': '1', 05:50:26 INFO - 'PAGER': '/bin/cat', 05:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:26 INFO - 'PWD': '/builds/slave/test', 05:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:26 INFO - 'SHELL': '/bin/bash', 05:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:26 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:26 INFO - 'USER': 'cltbld', 05:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:26 INFO - 'XPC_FLAGS': '0x0', 05:50:26 INFO - 'XPC_SERVICE_NAME': '0', 05:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:50:26 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-6t8cvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:50:26 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-DQIO09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:50:26 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-e9HOIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:50:26 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-4W7s8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:50:26 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-rFfU5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-IwlaAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-ABOmtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-XSMJhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-xB3bJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-PpZgN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:50:27 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)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-LRPRcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-DaV6LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:50:27 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)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-e_fXW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:50:27 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-zpxJwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:50:28 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)) 05:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:28 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-PVDhze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:50:28 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-gkyMrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:50:28 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-UxbW85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:31 INFO - Downloading blessings-1.6.tar.gz 05:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:50:31 INFO - Installing collected packages: blessings 05:50:31 INFO - Running setup.py install for blessings 05:50:32 INFO - Successfully installed blessings 05:50:32 INFO - Cleaning up... 05:50:32 INFO - Return code: 0 05:50:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:50:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:50:32 INFO - Reading from file tmpfile_stdout 05:50:32 INFO - Current package versions: 05:50:32 INFO - blessings == 1.6 05:50:32 INFO - blobuploader == 1.2.4 05:50:32 INFO - docopt == 0.6.1 05:50:32 INFO - functools32 == 3.2.3-2 05:50:32 INFO - jsonschema == 2.5.1 05:50:32 INFO - manifestparser == 1.1 05:50:32 INFO - mozInstall == 1.12 05:50:32 INFO - mozcrash == 0.17 05:50:32 INFO - mozdebug == 0.1 05:50:32 INFO - mozdevice == 0.48 05:50:32 INFO - mozfile == 1.2 05:50:32 INFO - mozhttpd == 0.7 05:50:32 INFO - mozinfo == 0.9 05:50:32 INFO - mozleak == 0.1 05:50:32 INFO - mozlog == 3.3 05:50:32 INFO - moznetwork == 0.27 05:50:32 INFO - mozprocess == 0.23 05:50:32 INFO - mozprofile == 0.28 05:50:32 INFO - mozrunner == 6.12 05:50:32 INFO - mozscreenshot == 0.1 05:50:32 INFO - mozsystemmonitor == 0.3 05:50:32 INFO - moztest == 0.7 05:50:32 INFO - mozversion == 1.4 05:50:32 INFO - psutil == 3.1.1 05:50:32 INFO - requests == 1.2.3 05:50:32 INFO - wsgiref == 0.1.2 05:50:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:32 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 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:32 INFO - 'HOME': '/Users/cltbld', 05:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:32 INFO - 'LOGNAME': 'cltbld', 05:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:32 INFO - 'MOZ_NO_REMOTE': '1', 05:50:32 INFO - 'NO_EM_RESTART': '1', 05:50:32 INFO - 'PAGER': '/bin/cat', 05:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:32 INFO - 'PWD': '/builds/slave/test', 05:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:32 INFO - 'SHELL': '/bin/bash', 05:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:32 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:32 INFO - 'USER': 'cltbld', 05:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:32 INFO - 'XPC_FLAGS': '0x0', 05:50:32 INFO - 'XPC_SERVICE_NAME': '0', 05:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:50:32 INFO - Cleaning up... 05:50:32 INFO - Return code: 0 05:50:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10913fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa109d633a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, '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.1UCwDmNLf3/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.gfMJWVAITT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:32 INFO - 'HOME': '/Users/cltbld', 05:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:32 INFO - 'LOGNAME': 'cltbld', 05:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:32 INFO - 'MOZ_NO_REMOTE': '1', 05:50:32 INFO - 'NO_EM_RESTART': '1', 05:50:32 INFO - 'PAGER': '/bin/cat', 05:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:32 INFO - 'PWD': '/builds/slave/test', 05:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:32 INFO - 'SHELL': '/bin/bash', 05:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:50:32 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:32 INFO - 'USER': 'cltbld', 05:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:32 INFO - 'XPC_FLAGS': '0x0', 05:50:32 INFO - 'XPC_SERVICE_NAME': '0', 05:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:50:33 INFO - Cleaning up... 05:50:33 INFO - Return code: 0 05:50:33 INFO - Running post-action listener: _resource_record_post_action 05:50:33 INFO - Running post-action listener: _start_resource_monitoring 05:50:33 INFO - Starting resource monitoring. 05:50:33 INFO - [mozharness: 2016-07-20 12:50:33.086652Z] Finished create-virtualenv step (success) 05:50:33 INFO - [mozharness: 2016-07-20 12:50:33.087652Z] Running install step. 05:50:33 INFO - Running pre-action listener: _resource_record_pre_action 05:50:33 INFO - Running main action method: install 05:50:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:50:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:50:33 INFO - Reading from file tmpfile_stdout 05:50:33 INFO - Detecting whether we're running mozinstall >=1.0... 05:50:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:50:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:50:33 INFO - Reading from file tmpfile_stdout 05:50:33 INFO - Output received: 05:50:33 INFO - Usage: mozinstall [options] installer 05:50:33 INFO - Options: 05:50:33 INFO - -h, --help show this help message and exit 05:50:33 INFO - -d DEST, --destination=DEST 05:50:33 INFO - Directory to install application into. [default: 05:50:33 INFO - "/builds/slave/test"] 05:50:33 INFO - --app=APP Application being installed. [default: firefox] 05:50:33 INFO - mkdir: /builds/slave/test/build/application 05:50:33 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'] 05:50:33 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 05:51:08 INFO - Reading from file tmpfile_stdout 05:51:08 INFO - Output received: 05:51:08 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:51:08 INFO - Running post-action listener: _resource_record_post_action 05:51:08 INFO - [mozharness: 2016-07-20 12:51:08.486533Z] Finished install step (success) 05:51:08 INFO - [mozharness: 2016-07-20 12:51:08.486695Z] Running run-tests step. 05:51:08 INFO - Running pre-action listener: _resource_record_pre_action 05:51:08 INFO - Running main action method: run_tests 05:51:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:08 INFO - 2016-07-20 05:51:08.501 screenresolution[1644:7513] starting screenresolution argv=screenresolution get 05:51:08 INFO - 2016-07-20 05:51:08.504 screenresolution[1644:7513] Display 0: 1600x1200x32@60 05:51:08 INFO - 2016-07-20 05:51:08.510 screenresolution[1645:7515] starting screenresolution argv=screenresolution list 05:51:08 INFO - Available Modes on Display 0 05:51:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:08 INFO - Intel Iris: 05:51:08 INFO - Chipset Model: Intel Iris 05:51:08 INFO - Type: GPU 05:51:08 INFO - Bus: Built-In 05:51:08 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:08 INFO - Vendor: Intel (0x8086) 05:51:08 INFO - Device ID: 0x0a2e 05:51:08 INFO - Revision ID: 0x0009 05:51:08 INFO - Displays: 05:51:08 INFO - Display: 05:51:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:08 INFO - Display Serial Number: 1600x1200 60 05:51:08 INFO - Main Display: Yes 05:51:08 INFO - Mirror: Off 05:51:08 INFO - Online: Yes 05:51:08 INFO - Rotation: Supported 05:51:08 INFO - Return code: 0 05:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:51:08 INFO - Python 2.7.3 05:51:08 INFO - Return code: 0 05:51:08 INFO - grabbing minidump binary from tooltool 05:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:51:08 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 0x1090a3d30>, '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 0x108e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10909bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:51:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:51:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:51:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:51:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:51:08 INFO - Return code: 0 05:51:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:51:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:51:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:51:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:51:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0247 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:51:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1UCwDmNLf3/Render', 05:51:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:08 INFO - 'HOME': '/Users/cltbld', 05:51:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:08 INFO - 'LOGNAME': 'cltbld', 05:51:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:51:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:08 INFO - 'MOZ_NO_REMOTE': '1', 05:51:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:51:08 INFO - 'NO_EM_RESTART': '1', 05:51:08 INFO - 'PAGER': '/bin/cat', 05:51:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:08 INFO - 'PWD': '/builds/slave/test', 05:51:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:51:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:08 INFO - 'SHELL': '/bin/bash', 05:51:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gfMJWVAITT/Listeners', 05:51:08 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 05:51:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:08 INFO - 'USER': 'cltbld', 05:51:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:08 INFO - 'XPC_FLAGS': '0x0', 05:51:08 INFO - 'XPC_SERVICE_NAME': '0', 05:51:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:51:09 INFO - mozversion application_buildid: 20160720030208 05:51:09 INFO - mozversion application_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 05:51:09 INFO - mozversion application_display_name: Nightly 05:51:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:51:09 INFO - mozversion application_name: Firefox 05:51:09 INFO - mozversion application_remotingname: firefox 05:51:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:51:09 INFO - mozversion application_vendor: Mozilla 05:51:09 INFO - mozversion application_version: 50.0a1 05:51:09 INFO - mozversion platform_buildid: 20160720030208 05:51:09 INFO - mozversion platform_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 05:51:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:51:09 INFO - mozversion platform_version: 50.0a1 05:51:09 INFO - using testdate: 1469019069 05:51:09 INFO - actual date: 1469019069 05:51:09 INFO - starting webserver on 'localhost:49268' 05:51:09 INFO - SUITE-START | Running 2 tests 05:51:09 INFO - TEST-START | dromaeo_css 05:51:09 INFO - operating with platform_type : mac_ 05:51:09 INFO - Initialising browser for dromaeo_css test... 05:51:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpqXeo6U/profile http://localhost:49268/getInfo.html) 05:51:11 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:51:11 INFO - PROCESS | 1655 | colorDepth:24 05:51:11 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:51:11 INFO - PROCESS | 1655 | __metrics 05:51:11 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:51:11 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:12 INFO - TEST-INFO | 1655: exit 0 05:51:12 INFO - Browser initialized. 05:51:12 INFO - Running cycle 1/1 for dromaeo_css test... 05:51:12 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpqXeo6U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:24 INFO - PROCESS | 1658 | RSS: Main: 245297152 05:51:24 INFO - PROCESS | 1658 | 05:52:15 INFO - PROCESS | 1658 | 1469019135533 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:54:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 05:54:28 INFO - PROCESS | 1658 | RSS: Main: 355377152 05:54:28 INFO - PROCESS | 1658 | 05:57:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 05:57:32 INFO - PROCESS | 1658 | RSS: Main: 376291328 05:57:32 INFO - PROCESS | 1658 | 06:00:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 06:00:34 INFO - PROCESS | 1658 | RSS: Main: 373903360 06:00:34 INFO - PROCESS | 1658 | 06:03:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 06:03:38 INFO - PROCESS | 1658 | RSS: Main: 371535872 06:03:38 INFO - PROCESS | 1658 | 06:06:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 06:06:42 INFO - PROCESS | 1658 | RSS: Main: 337723392 06:06:42 INFO - PROCESS | 1658 | 06:09:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 06:09:46 INFO - PROCESS | 1658 | RSS: Main: 390201344 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | __start_tp_report 06:09:46 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:09:46 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:09:46 INFO - PROCESS | 1658 | |i|pagename|runs| 06:09:46 INFO - PROCESS | 1658 | |0;dojo.html;3333.849821811474;3351.8156501392423;3360.3783300089476;3379.9831000844993;3417.8803741869024;5542.418046105151;5583.804566840161;5617.101263797793;5668.3198016238;5695.487406133447;5583.553315734752;5630.127330263806;5716.1425786131995;5723.112917497784;5753.626014309078;5114.181730923053;5143.974280128594;5170.482951704818;5184.844454666347;5286.497782710637;5297.337832770903;5297.417284098746;5298.205269209611;5317.12267475868;5343.893122137555;4242.40606315113;5111.718855462948;5152.690838549662;5188.662736922107;5195.766190521435;5169.767360468788;5192.7403629818355;5209.0363282792805;5209.479052094798;5211.1922651988625;5092.083424983502;5115.386153661547;5132.717700526469;5172.5344718975475;5212.50481204285;5578.0517312057045;5676.631018983753;5676.971615141939;5713.914291285634;5722.227499287626;6211.968940155315;6222.12890195373;6227.972384556543;6243.500519958392;6247.094171345175;6102.822530596007;6109.951287404306;6204.565680402374;6223.315435302113;6225.688715564203;5788.957987562237;5810.564207684441;5828.53371730269;5829.475347218686;5841.85395365115;5624.91562626561;6311.274203466568;6312.684365781693;6327.715252813542;6336.619802811846;5917.733701983421;5975.551833612496;5988.730507127189;6056.031035034462;6060.545459090586;6188.164597779242;6201.906971395432;6205.968970155211;6206.565540412218;6210.813675589739;6072.605280656831;6142.447179753844;6143.047827586731;6143.754249829957;6163.3528479509005;5555.638868477249;6059.36376680451;6107.84730381744;6112.358202388685;6180.66006369645;4568.428946381702;4599.827044103732;4890.510948905152;4909.3126962225315;4932.26016097581;4396.736195828261;4399.340098985178;4419.513853476115;4421.005273813366;4452.220861349251;5543.805966791224;5703.401142879938;5723.996601189634;5776.537876969833;5783.103618939072;6128.717018009172;6159.4456498915315;6213.37866213375;6226.1905952225925;6287.056941458726;5685.147227915846;5721.456461636138;5729.88540229193;5731.0543760279925;5763.4812866842185;4569.54304569547;5283.286756287929;5334.946650533522;5387.595930305164;5413.377461591967;6263.812085637438;6304.936950630435;6315.55791094619;6318.494520438446;6324.1778568786685;6193;6237.126802247598;6238.968805155945;6311.431971122621;6318.399752023551;6291.433770960635;6301.684915754102;6303.9598466252555;6306.936930630635;6311.116443697794;6119.571629985859;6126.632402055891;6188.381161883775;6197.473214776614;6202;6104.145419641165;6125.662799505599;6131.346082862053;6137.8979746733885;6256.0303153011355;6062.272527296753;6089.69551522431;6104.448125162133;6111.083337499233;6141.07883817431;4788.970371370033;6026.578139530191;6071.998120310276;6095.05526643371;6097.390260973867;5794.942050579441;5829.271341082364;5850.824475265749;5901.026330655393;5958.463738263577;6106.297775755845;6108.726966420949;6125.663088529996;6137.478314343152;6139.216166136355;5893.970530147321;5903.439173278532;5904.704764761659;5915.08316210988;5923.703814809329 06:09:46 INFO - PROCESS | 1658 | |1;ext.html;480.16691041043805;485.12192930795266;522.8039485193052;525.2200482283811;529.1242992846837;27321;32747.345053098954;42041;42919.14161716764;43021.56978430215;43946;44387.334189987094;44428;44439.55560444394;44489;9738.610455581767;9920.45437500937;9929.106380425761;9937.055979681954;9963.402195868235;7451.850962980737;8739.563021848913;8922.821543569125;8997.640094396216;9012.729618111467;11707.716995185941;11904.64286071415;11938.582149624764;12179.025677945787;12184.878151218463;11874.881251187508;11962.58130965412;11992.100592455601;12008.459619317151;12090.093243006722;8601.182887625666;11809.055275577934;11858.466369013415;11882.702932426671;11934.343611101385;31259.687403126132;32766.017019489453;32821;33607.15982100467;41105.383419248734;2985.208919636298;3713.7771733696063;3719.2800621873257;4243.448351714257;4427.579379958899;32033.51949720756;32221;33507.83246083778;33546.832265838515;33565.83217083923;3370.5112758650102;3389.068006298263;3417.743669224818;3419.692227699494;3423.127102588824;467310;476909;477752;478283;479533;58778;67916.98124528136;69081.30918690749;69255.30744692589;69643.30356696369;71129.53139920338;71175.64412177906;71233;71329.2867071333;71381.14475420925;17418.216180271917;17418.651626967643;17511.299548018193;17610.735838962424;17621.7356739649;13151.736965260641;13219.074664773374;13237.404316805767;13243.404046817916;13254.801177982337;18267.63464730698;19521.822993764905;20946.47633809167;20982.37052888416;21871.781282187294;8792.164744349277;8822.2060014599;8863.556822158995;8889.333300002525;8937.150970657776;857.7255278311005;900.8513595256841;959.4147569982305;1046.4506134279563;1058.6771034834449;25569.872150639127;29899.70100299013;30608;30815.52933673457;30984.53523197154;1060.6022741471947;1079.4818487126224;1136.8063533290012;1143.3597185575939;1147.5180424221846;14876.561719140429;15675;16147;16293.348266069224;16478.44584089468;714688;720110;720191;720637;723669;59789.70105149446;60239;60398;60424;60509.39490605125;1381.9239941803298;1407.8310602727738;1424.7079348733691;1427.186503692885;1428.0788891165141;29825.403491930472;30264.94072707533;30548.504690141195;30834.074977750704;31181.688183118786;52640.47359526356;53342.199867002804;53382.66543336448;53405;53472.73263633657;609410.9529452324;610621;611575;612146;615702;28048.719512805426;28693.56959645565;28813.423731524832;29831.552526712552;44966.77516612396;39076.2184756309;49495.50504494909;50301.47889647836;50489;52060;40468.39297410482;40502.392464113655;40825;41790.58209417989;42041.57958420377;40276;40344.798276008434;40678.38982415325;41630;42091.579084208766;38739.612603873604;38909.80545097256;38993.02517436973;39856.40215396713;39921.80039099902;33357.66642333545;35521.93434196978;36180.45729314115;36379.818100909324;36439.63560364363;20214.898925505277;24709.752902470744;25472.108476203244;26056.34859128537;26590.601140983286 06:09:46 INFO - PROCESS | 1658 | |2;jquery.html;370.7775334799123;381.5802617121167;386.4608870625477;397.8289335585697;404.8684177642267;16030.59923501913;16855.494335169933;19003.61992760144;21148.154073837028;21156.682649760267;17005;19786;19877.701834472493;20683.276085337016;20895.268665596708;5337.5463085637575;5861.0915308127205;6004.18943442634;6119.571629985903;6144.5698801083945;5987.55093367997;6097.390260973912;6109.847253818646;6112.502436902965;6117.418845209698;5042.420121685998;5819.185314056035;5973.163757074013;6020.096985452174;6050.636961782285;5953.3748956359605;5967.194428752106;6006.42938920804;6009.308929473123;6009.707912798725;5070.569001634865;5333.5199832015305;5388.757505912205;5396.541293990015;5428.185772134172;12934.965202783755;13064.477420903153;13221.603351899459;18124.90937545317;24820.751792482024;2128.670056141285;2170.674398840171;2176.3144609447972;2195.1438938813876;2841.247069526577;9240.399374040666;9546.61813527465;9935.453550054744;9966.252531060127;10132.594696212143;1739.90430526321;1770.1768677564864;1799.109440826799;1840.8245776036365;1860.479065861561;101264.98735012629;111341;111544;131540;138314.92527612095;10965.780684386267;11287.29105014807;11404.372759498141;11451.427428628534;11686.766264674829;14473.131612103307;14529.709405811824;14536.563903082924;14543.563693089225;14552.708945821236;6615.76844810439;8491.61787719554;8559.186877246651;8634.913650863411;8646.567671616394;8477.27943124829;8479.448835825651;8501.617427215666;8657.091005444337;8684.305255579538;4343.348497725303;4449.3993310902615;4459.799309031101;4522.8643140705835;4528.88677783058;1867.8972656504022;1916.453810663982;1916.5208697825544;1919.9136038878285;1927.4892153579312;715.2171335994002;837.5267973595021;929.7350255177242;934.275936149476;939.7415710679618;20024.799752002586;21013.68479472809;21095.050722717515;21123.366299011053;21295.254666086614;808.4138999225514;818.3003531980071;908.1191244492832;954.5609019850947;996.5465713100522;10542.631007914686;11035.668929932115;12017.399130043463;12050.75898482043;12131.75736485283;613021;621091;623684;630806;630986;91118;126709.36645316899;148850;152139;155633;944.6929747831982;980.9362391444533;1006.225206590921;1009.0565321424344;1009.9545520451597;18318.450446486626;18662.440126796217;18720.53198670044;18915.26539733493;18990.715139273198;44775;57808.71095644495;58351.708241458524;58885.705571471866;59312.51718707067;85138.57430713055;85387;87253.56373218093;88097.1190288089;88433;5352.946470535245;6371.840703982438;6559;6955.956606509064;7659.808504787202;10645.574177032831;12259.816102758645;12524.49902003946;12944.676383090122;13038.608841734764;10877.619283325046;10884.455777211266;10920.890791092304;10953.56185752561;11003.834942476029;9145.451272923647;10662.73343166427;10693.465326733476;10897.12822974176;10973.780524389307;10823.188260880308;11143.33140011602;11277.266977646426;11444.828327574927;11488.885111148782;7645.770626881203;7833.686652533835;7897.723579674684;8074.475159114639;8226.712065077932;9931.354461959949;10843.1867609928;11246.943765281449;11250.606228781953;11260.493277802683 06:09:46 INFO - PROCESS | 1658 | |3;mootools.html;1543.529223586806;1589.8410158984102;1803.8737288389818;1945.7081437784339;1951.3853136262085;8445.91554084459;8891.510966896818;8901.376903616749;8901.688440904562;8914.50970196639;7818.257265559777;8037.27664510194;8426.410151289412;8651.394402391836;8691.91308086919;2321.3152120124605;2345.29641107667;2347.495288512969;2347.530493901218;2354.0583766493455;2301.1255722825304;2317.1194945920524;2348.0842471436154;2349.3186975777003;2353.176388264111;2063.411927600634;2144.9463763405884;2146.6672665736837;2150.666646669769;2329.813614910803;2252.2117258959315;2337;2341.555104530134;2343.7304709958403;2344.167820423742;2309.2841219222096;2314.4329639238326;2314.7338056123594;2317.165820304689;2322.198841399714;8779.03430622631;8827.646894124227;8833.69082082124;8845.115488451167;8861.335399845037;5161.3806343238675;5326.36083669962;5327.254184414185;5332.466753324676;5333.1733581294575;2983.4928062229474;3369.534252600127;3433.759636825423;3571.821408929543;3595.496630471736;886.898006729228;1042.8957104289586;1045.8326667733127;1062.3678911047914;1066.951987160572;11562;11732.35472049037;11755.7061073473;11757.823632645517;11790.705232369173;4489.191945449785;4584.633229341644;4684.461286951976;4737.928931066037;4738.289256611536;9840.507974601242;9843.704688859345;9847.950760246153;9850.458224797561;9860.605575776888;5272.393674727456;5272.657277276965;5276.683398996072;5282.339707536558;5282.498162674539;4350.3039513678295;4488.685791994529;4679.064187162581;4850.733209673432;4857.951420485821;3222.46829273172;3743.2139250757646;3777.301199278142;3911.3155197840833;3952.3083460394314;1043.5147656339839;1225.1791299829135;1229.4221715793562;1232.2852745407642;1240.3054289597853;660.4617236951868;670.0953712488102;862.094800459515;862.1464749897556;895.2748273898163;5632.211490391349;5736.541076713853;5959.314678811906;6037.18498002764;6157;386.4222986634999;445.56557356577076;450.4639479019956;923.8798956135794;1055.2507719519228;3856.0745421098736;4032.862582831104;4035.616616421435;4080.1227736036894;4081.3877918311896;9290.1174388433;10126.848097278547;10142.543585538668;10964.56141754321;10984.121270298358;10422.635207767691;10797.46012699362;10811.783764324828;10812.405317707444;10824;7977.920220797834;8048.114707382183;8196.42625016255;8298.460600061097;8302.211289927447;5947.464728174485;6029.788957386646;6161.476274516716;6229.781957631461;6250.218722659668;10015.649452269136;10045.296829221885;10047.246456515644;10049.397036177852;10055.446950417798;9590.472524011246;10230.590776368861;10235.84646230322;10246;10263.538140783832;6965.477589180933;7178.353948144692;7187.101612298463;7596.544207347577;7743.303102720783;7771.106322772954;7909.288164065261;7986.600669966595;7999.800004999689;8183.795405114682;7210.747623833141;7560.546367217985;7591;7616.657250423855;7651;7345.55926644403;7445.478816482794;7451.776446706607;7492.550446973199;7535.058117735283;6980.336867997532;7104;7110.431165506644;7120.323569260912;7120.537165084253;4253.957460425356;4811.230203167476;4905.681130726492;4911.165101932608;5006.69959802413;4920.975395123001;4998.375203099613;5038.773255203451;5083.669561478492;5211.478852114758 06:09:46 INFO - PROCESS | 1658 | |4;prototype.html;242.5161802204602;305.51880787759274;309.3426468753898;314.09697120777776;316.3893685187587;4735.834245801398;5350.438203988583;5380.7847686092555;5471.015217260897;5500.477454641813;5135.460776618448;5200.531952124308;5377.327834020747;5406.83779486616;5436.9184462233;3071.539269109629;3851.0372406898277;3931.7811478441777;4104.199681062194;4121.1345617420375;4124.381342798574;4151.252774500589;4152.73007254529;4169.854055108071;4199.895002624943;4093.3041382964966;4103.835846566134;4106.79466026699;4129.648979836717;4131.6281534661875;3403.5915690117095;3718.888433347004;3894.493715816954;4112.979435102805;4143.337066069429;2862.398896231794;3143.402753476832;3596.514470546469;3601.8559257629663;3639.3813051780994;4659.8564660256325;5258.6581872178185;5285.233641122054;5304.549113325372;5352.143657014898;4583.449986001668;4723.362346083303;4748.643851711101;4768.47546769855;4801.615870730333;4972.662848461217;5664.272396918938;5691;5707.258056452634;5731.512821410185;3340.2818394045153;3721.702263818915;3783.2622638585485;3992.6207010333974;4000.139969906455;5650.886982260373;5986.138940383428;5993.760249589967;6001.399860014008;6035.366286539938;3985.980070099689;3992.4610177626228;4003.3594624859884;4010.61899119589;4022.35642297231;3442.397580423416;4044.737092089005;4098.631123198927;4130;4130.194612050621;4443.622292105143;4738.218193998021;4750.263709125091;4751.239801631722;4762.880927976828;5665.405132461115;5684.744186511617;5698.287714035746;5704.344000440003;5709.601147718836;1233.9383030848421;1235.610782603495;1377.9242141682103;1410.8307003159482;1420.4247279851677;766.9348105411102;831.5925196653596;871.825634873028;882.9536998656613;893.41927746965;4459.063596644676;4579.138038725796;4837.879053023632;5011.974940125276;5349;4833.622417610964;5263.894722105537;5615.904898544826;5873.3245676746865;5881.882362352814;5119.360079990001;5334.866628334245;5362.27609272751;5410.458954104637;5423.698312405073;2974.301039255773;3032.7725420593542;3094.05631282461;3108.4870996285813;3126.2340726522148;5345.33183352081;5817.825465236049;6122.54080943931;6193.535484838655;6201.6899155042065;4961.940794570285;5594.636348637326;5963.403659634002;6054.636721796706;6060.969695151584;5544.833654990357;5941.435563621536;6039.071050540484;6049.516038716892;6058.909116363258;5610.354809196831;5897.262842144732;6019.428154325332;6026.758929642765;6032.698365081641;4398.362237475612;4848.733319667311;5312.389075256395;5457.781688732565;5856.59003869725;5119.360079990001;6114.969425152845;6125.356837532173;6183.628982261079;6186.938130618816;3685.944710829286;3875.534935807721;3904.0239940014994;3918.746720635589;3972.741771784825;5834.920539700169;5852.590318677822;5857.5313974881055;5862.944669959277;5890.14592884038;4693.530646935279;4906.681065730716;5650.26546548946;5793.333766616893;5814.034842230409;5043.9243411348125;5156.762377029561;5175.404828444777;5366.463353664602;5673.659580425187;5416.566674666096;5633.408492108433;5650.344338161058;5709.514691251124;5711.915150969783;5280.973595132;5491.693488734821;5719.513841323368;5743.368229494836;5776.967092276588;5291.735413229246;5574.832755017356;5597.63615365;5609.915851262316;5639.210510528611 06:09:46 INFO - PROCESS | 1658 | |5;yui.html;721.7798571435711;744.4565467208939;764.6941223510595;822.7719484330157;825.7316372179043;14944.40222391103;16119.919400402996;16129.51611451657;16136.677266454664;16141.354345826183;14597.78103328451;14803.40786368544;14813;14813.481528146516;14861.405543778235;716.1012928774381;736.3740820302727;886.0209468537254;914;914.1407077347305;873.6199753107387;896.7175339767984;902.3728508686432;906.3112034853526;907.120093509295;964.6189755046752;966.0629189686028;966.6133546581353;967.4195482710388;968.9612415503371;754.2645920227789;962.451402700461;963.2438535749438;963.5278713430368;966.3718582921088;693.3343989769777;709.2092317066486;710.2187593646998;714.4141803720951;715.906932098829;15040.247987600686;15076.02005869615;15104.924475377662;15112;15120.773188402183;13708.24604646744;13951.58145255644;14090.859091409056;14163.858361416356;14165.645858853508;12759.872401275961;12856.678583035498;12882.1626594272;13032.348382580833;13089.149205301625;4571.748553829538;4614.976925115387;4743.217369134123;4805.303231031516;4822.710637361734;96059.51970240104;96645;96862;97273.0272697271;99885.5005724974;11007.504662290215;11050.336979781077;11128.777424451466;11171.59716289508;11300.265482743594;21911.452213694465;21919.561608768054;21924.23265185711;21930.342089737333;21968.45078873009;7960.203979602109;8007.479513831699;8118.2693557580105;8125.9187408126345;8254;4434.733915965053;4537.841175558906;4571.428571428572;4683.95316046842;4709.152352576569;3400.4219282721997;3462.117160124152;3506.333796578642;3508.1054331145906;3513.0865974846724;589.4253103224322;604.6667099046591;627.4948666323562;633.7591715148214;644.4779728419995;421.2806335106878;581.5522048022999;598.8862116197909;614.2598169206096;637.7082236548058;8631.43895646781;9093.272538197061;9138.54307284633;9535.332526723201;9684.225261979025;578.6123297390758;599.9850003749941;618.8483821463681;660.9636469994197;669.1167658690482;4351.6301114405;4640.976795116003;4665.930011049837;4728.196206644879;4750.904981900412;88651.11348886428;90231;136522.95215571774;144090;152465;105856.47071764746;114799;116315;126556;128362;105615;110621;114340.85659143468;117786;117843;9604.471754053595;9791.804163916824;10209.591616335263;10229.641962531276;10251.948740256252;57292;64779.67610162107;66990;67742;69295.61408771898;94498;96737.51631241798;97646.51176744071;97690;98349;13188.934055330046;13547.25490098054;13629.659258518219;13794.724105517633;14036.508722194674;14238.928805355907;15469.922650386676;15583.76624350613;16035.358585656442;16054.036757794569;14243.64390890236;14498.782518262446;14598.051126676732;14632.122072675673;14917.403303867724;14171.787423188867;14380.928095359875;14419.279036048365;14680.265986700835;14842.48051318242;13991.650208744863;14218.857811421753;14499.275036248357;14647.92676036613;14656.267186640838;11378.43107844588;11459.885401145882;11538.82691759641;11675.532978680758;12157.08821838348;10508.579656813641;11136.665900023012;11157.944210278896;11575.10622344414;11719.648410547697 06:09:46 INFO - PROCESS | 1658 | __end_tp_report 06:09:46 INFO - PROCESS | 1658 | __start_cc_report 06:09:46 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1236 06:09:46 INFO - PROCESS | 1658 | __end_cc_report 06:09:46 INFO - PROCESS | 1658 | __startTimestamp1469020186072__endTimestamp 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:09:46 INFO - PROCESS | 1658 | Number of tests: 6 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5695.02 Median:5901.03 stddev:651.17 (11.0%) stddev-sans-first:626.27 06:09:46 INFO - PROCESS | 1658 | Values: 3333.8 3351.8 3360.4 3380.0 3417.9 5542.4 5583.8 5617.1 5668.3 5695.5 5583.6 5630.1 5716.1 5723.1 5753.6 5114.2 5144.0 5170.5 5184.8 5286.5 5297.3 5297.4 5298.2 5317.1 5343.9 4242.4 5111.7 5152.7 5188.7 5195.8 5169.8 5192.7 5209.0 5209.5 5211.2 5092.1 5115.4 5132.7 5172.5 5212.5 5578.1 5676.6 5677.0 5713.9 5722.2 6212.0 6222.1 6228.0 6243.5 6247.1 6102.8 6110.0 6204.6 6223.3 6225.7 5789.0 5810.6 5828.5 5829.5 5841.9 5624.9 6311.3 6312.7 6327.7 6336.6 5917.7 5975.6 5988.7 6056.0 6060.5 6188.2 6201.9 6206.0 6206.6 6210.8 6072.6 6142.4 6143.0 6143.8 6163.4 5555.6 6059.4 6107.8 6112.4 6180.7 4568.4 4599.8 4890.5 4909.3 4932.3 4396.7 4399.3 4419.5 4421.0 4452.2 5543.8 5703.4 5724.0 5776.5 5783.1 6128.7 6159.4 6213.4 6226.2 6287.1 5685.1 5721.5 5729.9 5731.1 5763.5 4569.5 5283.3 5334.9 5387.6 5413.4 6263.8 6304.9 6315.6 6318.5 6324.2 6193.0 6237.1 6239.0 6311.4 6318.4 6291.4 6301.7 6304.0 6306.9 6311.1 6119.6 6126.6 6188.4 6197.5 6202.0 6104.1 6125.7 6131.3 6137.9 6256.0 6062.3 6089.7 6104.4 6111.1 6141.1 4789.0 6026.6 6072.0 6095.1 6097.4 5794.9 5829.3 5850.8 5901.0 5958.5 6106.3 6108.7 6125.7 6137.5 6139.2 5894.0 5903.4 5904.7 5915.1 5923.7 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:74206.92 Median:38951.42 stddev:163460.50 (419.7%) stddev-sans-first:163825.32 06:09:46 INFO - PROCESS | 1658 | Values: 480.2 485.1 522.8 525.2 529.1 27321.0 32747.3 42041.0 42919.1 43021.6 43946.0 44387.3 44428.0 44439.6 44489.0 9738.6 9920.5 9929.1 9937.1 9963.4 7451.9 8739.6 8922.8 8997.6 9012.7 11707.7 11904.6 11938.6 12179.0 12184.9 11874.9 11962.6 11992.1 12008.5 12090.1 8601.2 11809.1 11858.5 11882.7 11934.3 31259.7 32766.0 32821.0 33607.2 41105.4 2985.2 3713.8 3719.3 4243.4 4427.6 32033.5 32221.0 33507.8 33546.8 33565.8 3370.5 3389.1 3417.7 3419.7 3423.1 467310.0 476909.0 477752.0 478283.0 479533.0 58778.0 67917.0 69081.3 69255.3 69643.3 71129.5 71175.6 71233.0 71329.3 71381.1 17418.2 17418.7 17511.3 17610.7 17621.7 13151.7 13219.1 13237.4 13243.4 13254.8 18267.6 19521.8 20946.5 20982.4 21871.8 8792.2 8822.2 8863.6 8889.3 8937.2 857.7 900.9 959.4 1046.5 1058.7 25569.9 29899.7 30608.0 30815.5 30984.5 1060.6 1079.5 1136.8 1143.4 1147.5 14876.6 15675.0 16147.0 16293.3 16478.4 714688.0 720110.0 720191.0 720637.0 723669.0 59789.7 60239.0 60398.0 60424.0 60509.4 1381.9 1407.8 1424.7 1427.2 1428.1 29825.4 30264.9 30548.5 30834.1 31181.7 52640.5 53342.2 53382.7 53405.0 53472.7 609411.0 610621.0 611575.0 612146.0 615702.0 28048.7 28693.6 28813.4 29831.6 44966.8 39076.2 49495.5 50301.5 50489.0 52060.0 40468.4 40502.4 40825.0 41790.6 42041.6 40276.0 40344.8 40678.4 41630.0 42091.6 38739.6 38909.8 38993.0 39856.4 39921.8 33357.7 35521.9 36180.5 36379.8 36439.6 20214.9 24709.8 25472.1 26056.3 26590.6 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35884.54 Median:1606.01 stddev:104402.06 (6500.7%) stddev-sans-first:104660.88 06:09:46 INFO - PROCESS | 1658 | Values: 370.8 381.6 386.5 397.8 404.9 16030.6 16855.5 19003.6 21148.2 21156.7 17005.0 19786.0 19877.7 20683.3 20895.3 5337.5 5861.1 6004.2 6119.6 6144.6 5987.6 6097.4 6109.8 6112.5 6117.4 5042.4 5819.2 5973.2 6020.1 6050.6 5953.4 5967.2 6006.4 6009.3 6009.7 5070.6 5333.5 5388.8 5396.5 5428.2 12935.0 13064.5 13221.6 18124.9 24820.8 2128.7 2170.7 2176.3 2195.1 2841.2 9240.4 9546.6 9935.5 9966.3 10132.6 1739.9 1770.2 1799.1 1840.8 1860.5 101265.0 111341.0 111544.0 131540.0 138314.9 10965.8 11287.3 11404.4 11451.4 11686.8 14473.1 14529.7 14536.6 14543.6 14552.7 6615.8 8491.6 8559.2 8634.9 8646.6 8477.3 8479.4 8501.6 8657.1 8684.3 4343.3 4449.4 4459.8 4522.9 4528.9 1867.9 1916.5 1916.5 1919.9 1927.5 715.2 837.5 929.7 934.3 939.7 20024.8 21013.7 21095.1 21123.4 21295.3 808.4 818.3 908.1 954.6 996.5 10542.6 11035.7 12017.4 12050.8 12131.8 613021.0 621091.0 623684.0 630806.0 630986.0 91118.0 126709.4 148850.0 152139.0 155633.0 944.7 980.9 1006.2 1009.1 1010.0 18318.5 18662.4 18720.5 18915.3 18990.7 44775.0 57808.7 58351.7 58885.7 59312.5 85138.6 85387.0 87253.6 88097.1 88433.0 5352.9 6371.8 6559.0 6956.0 7659.8 10645.6 12259.8 12524.5 12944.7 13038.6 10877.6 10884.5 10920.9 10953.6 11003.8 9145.5 10662.7 10693.5 10897.1 10973.8 10823.2 11143.3 11277.3 11444.8 11488.9 7645.8 7833.7 7897.7 8074.5 8226.7 9931.4 10843.2 11246.9 11250.6 11260.5 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5667.23 Median:4830.98 stddev:3212.34 (66.5%) stddev-sans-first:3206.41 06:09:46 INFO - PROCESS | 1658 | Values: 1543.5 1589.8 1803.9 1945.7 1951.4 8445.9 8891.5 8901.4 8901.7 8914.5 7818.3 8037.3 8426.4 8651.4 8691.9 2321.3 2345.3 2347.5 2347.5 2354.1 2301.1 2317.1 2348.1 2349.3 2353.2 2063.4 2144.9 2146.7 2150.7 2329.8 2252.2 2337.0 2341.6 2343.7 2344.2 2309.3 2314.4 2314.7 2317.2 2322.2 8779.0 8827.6 8833.7 8845.1 8861.3 5161.4 5326.4 5327.3 5332.5 5333.2 2983.5 3369.5 3433.8 3571.8 3595.5 886.9 1042.9 1045.8 1062.4 1067.0 11562.0 11732.4 11755.7 11757.8 11790.7 4489.2 4584.6 4684.5 4737.9 4738.3 9840.5 9843.7 9848.0 9850.5 9860.6 5272.4 5272.7 5276.7 5282.3 5282.5 4350.3 4488.7 4679.1 4850.7 4858.0 3222.5 3743.2 3777.3 3911.3 3952.3 1043.5 1225.2 1229.4 1232.3 1240.3 660.5 670.1 862.1 862.1 895.3 5632.2 5736.5 5959.3 6037.2 6157.0 386.4 445.6 450.5 923.9 1055.3 3856.1 4032.9 4035.6 4080.1 4081.4 9290.1 10126.8 10142.5 10964.6 10984.1 10422.6 10797.5 10811.8 10812.4 10824.0 7977.9 8048.1 8196.4 8298.5 8302.2 5947.5 6029.8 6161.5 6229.8 6250.2 10015.6 10045.3 10047.2 10049.4 10055.4 9590.5 10230.6 10235.8 10246.0 10263.5 6965.5 7178.4 7187.1 7596.5 7743.3 7771.1 7909.3 7986.6 7999.8 8183.8 7210.7 7560.5 7591.0 7616.7 7651.0 7345.6 7445.5 7451.8 7492.6 7535.1 6980.3 7104.0 7110.4 7120.3 7120.5 4254.0 4811.2 4905.7 4911.2 5006.7 4921.0 4998.4 5038.8 5083.7 5211.5 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4622.81 Median:5261.28 stddev:1434.72 (27.3%) stddev-sans-first:1400.57 06:09:46 INFO - PROCESS | 1658 | Values: 242.5 305.5 309.3 314.1 316.4 4735.8 5350.4 5380.8 5471.0 5500.5 5135.5 5200.5 5377.3 5406.8 5436.9 3071.5 3851.0 3931.8 4104.2 4121.1 4124.4 4151.3 4152.7 4169.9 4199.9 4093.3 4103.8 4106.8 4129.6 4131.6 3403.6 3718.9 3894.5 4113.0 4143.3 2862.4 3143.4 3596.5 3601.9 3639.4 4659.9 5258.7 5285.2 5304.5 5352.1 4583.4 4723.4 4748.6 4768.5 4801.6 4972.7 5664.3 5691.0 5707.3 5731.5 3340.3 3721.7 3783.3 3992.6 4000.1 5650.9 5986.1 5993.8 6001.4 6035.4 3986.0 3992.5 4003.4 4010.6 4022.4 3442.4 4044.7 4098.6 4130.0 4130.2 4443.6 4738.2 4750.3 4751.2 4762.9 5665.4 5684.7 5698.3 5704.3 5709.6 1233.9 1235.6 1377.9 1410.8 1420.4 766.9 831.6 871.8 883.0 893.4 4459.1 4579.1 4837.9 5012.0 5349.0 4833.6 5263.9 5615.9 5873.3 5881.9 5119.4 5334.9 5362.3 5410.5 5423.7 2974.3 3032.8 3094.1 3108.5 3126.2 5345.3 5817.8 6122.5 6193.5 6201.7 4961.9 5594.6 5963.4 6054.6 6061.0 5544.8 5941.4 6039.1 6049.5 6058.9 5610.4 5897.3 6019.4 6026.8 6032.7 4398.4 4848.7 5312.4 5457.8 5856.6 5119.4 6115.0 6125.4 6183.6 6186.9 3685.9 3875.5 3904.0 3918.7 3972.7 5834.9 5852.6 5857.5 5862.9 5890.1 4693.5 4906.7 5650.3 5793.3 5814.0 5043.9 5156.8 5175.4 5366.5 5673.7 5416.6 5633.4 5650.3 5709.5 5711.9 5281.0 5491.7 5719.5 5743.4 5777.0 5291.7 5574.8 5597.6 5609.9 5639.2 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24067.62 Median:1967.18 stddev:36722.83 (1866.8%) stddev-sans-first:36784.01 06:09:46 INFO - PROCESS | 1658 | Values: 721.8 744.5 764.7 822.8 825.7 14944.4 16119.9 16129.5 16136.7 16141.4 14597.8 14803.4 14813.0 14813.5 14861.4 716.1 736.4 886.0 914.0 914.1 873.6 896.7 902.4 906.3 907.1 964.6 966.1 966.6 967.4 969.0 754.3 962.5 963.2 963.5 966.4 693.3 709.2 710.2 714.4 715.9 15040.2 15076.0 15104.9 15112.0 15120.8 13708.2 13951.6 14090.9 14163.9 14165.6 12759.9 12856.7 12882.2 13032.3 13089.1 4571.7 4615.0 4743.2 4805.3 4822.7 96059.5 96645.0 96862.0 97273.0 99885.5 11007.5 11050.3 11128.8 11171.6 11300.3 21911.5 21919.6 21924.2 21930.3 21968.5 7960.2 8007.5 8118.3 8125.9 8254.0 4434.7 4537.8 4571.4 4684.0 4709.2 3400.4 3462.1 3506.3 3508.1 3513.1 589.4 604.7 627.5 633.8 644.5 421.3 581.6 598.9 614.3 637.7 8631.4 9093.3 9138.5 9535.3 9684.2 578.6 600.0 618.8 661.0 669.1 4351.6 4641.0 4665.9 4728.2 4750.9 88651.1 90231.0 136523.0 144090.0 152465.0 105856.5 114799.0 116315.0 126556.0 128362.0 105615.0 110621.0 114340.9 117786.0 117843.0 9604.5 9791.8 10209.6 10229.6 10251.9 57292.0 64779.7 66990.0 67742.0 69295.6 94498.0 96737.5 97646.5 97690.0 98349.0 13188.9 13547.3 13629.7 13794.7 14036.5 14238.9 15469.9 15583.8 16035.4 16054.0 14243.6 14498.8 14598.1 14632.1 14917.4 14171.8 14380.9 14419.3 14680.3 14842.5 13991.7 14218.9 14499.3 14647.9 14656.3 11378.4 11459.9 11538.8 11675.5 12157.1 10508.6 11136.7 11157.9 11575.1 11719.6 06:09:46 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:09:46 INFO - PROCESS | 1658 | 06:09:46 INFO - TEST-INFO | 1658: exit 0 06:09:46 INFO - TEST-OK | dromaeo_css | took 1117556ms 06:09:46 INFO - TEST-START | kraken 06:09:46 INFO - operating with platform_type : mac_ 06:09:46 INFO - Initialising browser for kraken test... 06:09:46 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpueRTqX/profile http://localhost:49268/getInfo.html) 06:09:48 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 06:09:48 INFO - PROCESS | 1667 | colorDepth:24 06:09:48 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 06:09:48 INFO - PROCESS | 1667 | __metrics 06:09:49 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:09:49 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:09:49 INFO - TEST-INFO | 1667: exit 0 06:09:49 INFO - Browser initialized. 06:09:49 INFO - Running cycle 1/1 for kraken test... 06:09:49 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpueRTqX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:10:01 INFO - PROCESS | 1669 | RSS: Main: 245805056 06:10:01 INFO - PROCESS | 1669 | 06:10:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 06:10:05 INFO - PROCESS | 1669 | RSS: Main: 294727680 06:10:05 INFO - PROCESS | 1669 | 06:10:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 06:10:08 INFO - PROCESS | 1669 | RSS: Main: 305180672 06:10:08 INFO - PROCESS | 1669 | 06:10:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 06:10:12 INFO - PROCESS | 1669 | RSS: Main: 393027584 06:10:12 INFO - PROCESS | 1669 | 06:10:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 06:10:15 INFO - PROCESS | 1669 | RSS: Main: 294535168 06:10:15 INFO - PROCESS | 1669 | 06:10:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:10:18 INFO - PROCESS | 1669 | RSS: Main: 299003904 06:10:18 INFO - PROCESS | 1669 | 06:10:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 06:10:22 INFO - PROCESS | 1669 | RSS: Main: 336220160 06:10:22 INFO - PROCESS | 1669 | 06:10:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 06:10:25 INFO - PROCESS | 1669 | RSS: Main: 336486400 06:10:25 INFO - PROCESS | 1669 | 06:10:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 06:10:28 INFO - PROCESS | 1669 | RSS: Main: 335081472 06:10:28 INFO - PROCESS | 1669 | 06:10:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:10:31 INFO - PROCESS | 1669 | RSS: Main: 314163200 06:10:31 INFO - PROCESS | 1669 | 06:10:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:10:33 INFO - PROCESS | 1669 | RSS: Main: 321028096 06:10:33 INFO - PROCESS | 1669 | 06:10:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:10:36 INFO - PROCESS | 1669 | RSS: Main: 318357504 06:10:36 INFO - PROCESS | 1669 | 06:10:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:10:40 INFO - PROCESS | 1669 | RSS: Main: 328118272 06:10:40 INFO - PROCESS | 1669 | 06:10:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:10:43 INFO - PROCESS | 1669 | RSS: Main: 330756096 06:10:43 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 06:10:45 INFO - PROCESS | 1669 | RSS: Main: 374317056 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | __start_tp_report 06:10:45 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 06:10:45 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 06:10:45 INFO - PROCESS | 1669 | |i|pagename|runs| 06:10:45 INFO - PROCESS | 1669 | |0;ai-astar;101;112;109;110;111;111;111;109;110;111 06:10:45 INFO - PROCESS | 1669 | |1;audio-beat-detection;149;112;116;196;137;92;153;136;178;113 06:10:45 INFO - PROCESS | 1669 | |2;audio-dft;186;185;184;181;186;236;252;181;181;185 06:10:45 INFO - PROCESS | 1669 | |3;audio-fft;76;75;58;89;59;57;64;61;84;58 06:10:45 INFO - PROCESS | 1669 | |4;audio-oscillator;84;90;94;73;99;101;132;69;69;126 06:10:45 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;86;89;91;109;116;136;85;88;85 06:10:45 INFO - PROCESS | 1669 | |6;imaging-darkroom;94;94;94;95;93;94;95;94;94;93 06:10:45 INFO - PROCESS | 1669 | |7;imaging-desaturate;85;85;94;88;87;84;84;82;87;84 06:10:45 INFO - PROCESS | 1669 | |8;json-parse-financial;55;53;52;52;53;55;52;51;53;52 06:10:45 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;67;70;80;77;75;71;75;74;71;73 06:10:45 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;83;87;84;83;82;82;87;96;103;80 06:10:45 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;138;137;135;135;134;136;137;140;246;149 06:10:45 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;163;218;235;166;164;164;161;165;164;161 06:10:45 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;69;73;66;67;66;66;67;68;66;67 06:10:45 INFO - PROCESS | 1669 | __end_tp_report 06:10:45 INFO - PROCESS | 1669 | __start_cc_report 06:10:45 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2113 06:10:45 INFO - PROCESS | 1669 | __end_cc_report 06:10:45 INFO - PROCESS | 1669 | __startTimestamp1469020245808__endTimestamp 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | ------- Summary: start ------- 06:10:45 INFO - PROCESS | 1669 | Number of tests: 14 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 06:10:45 INFO - PROCESS | 1669 | Values: 101.0 112.0 109.0 110.0 111.0 111.0 111.0 109.0 110.0 111.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:151.00 stddev:32.00 (21.2%) stddev-sans-first:33.70 06:10:45 INFO - PROCESS | 1669 | Values: 149.0 112.0 116.0 196.0 137.0 92.0 153.0 136.0 178.0 113.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:195.70 Median:185.50 stddev:25.81 (13.9%) stddev-sans-first:27.14 06:10:45 INFO - PROCESS | 1669 | Values: 186.0 185.0 184.0 181.0 186.0 236.0 252.0 181.0 181.0 185.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:68.10 Median:69.50 stddev:11.91 (17.1%) stddev-sans-first:12.29 06:10:45 INFO - PROCESS | 1669 | Values: 76.0 75.0 58.0 89.0 59.0 57.0 64.0 61.0 84.0 58.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:78.50 stddev:21.98 (28.0%) stddev-sans-first:23.03 06:10:45 INFO - PROCESS | 1669 | Values: 84.0 90.0 94.0 73.0 99.0 101.0 132.0 69.0 69.0 126.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:97.30 Median:87.00 stddev:17.31 (19.9%) stddev-sans-first:18.03 06:10:45 INFO - PROCESS | 1669 | Values: 88.0 86.0 89.0 91.0 109.0 116.0 136.0 85.0 88.0 85.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 06:10:45 INFO - PROCESS | 1669 | Values: 94.0 94.0 94.0 95.0 93.0 94.0 95.0 94.0 94.0 93.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:3.33 (3.9%) stddev-sans-first:3.52 06:10:45 INFO - PROCESS | 1669 | Values: 85.0 85.0 94.0 88.0 87.0 84.0 84.0 82.0 87.0 84.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.32 (2.5%) stddev-sans-first:1.13 06:10:45 INFO - PROCESS | 1669 | Values: 55.0 53.0 52.0 52.0 53.0 55.0 52.0 51.0 53.0 52.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:74.50 stddev:3.74 (5.0%) stddev-sans-first:3.20 06:10:45 INFO - PROCESS | 1669 | Values: 67.0 70.0 80.0 77.0 75.0 71.0 75.0 74.0 71.0 73.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:83.50 stddev:7.27 (8.7%) stddev-sans-first:7.59 06:10:45 INFO - PROCESS | 1669 | Values: 83.0 87.0 84.0 83.0 82.0 82.0 87.0 96.0 103.0 80.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:137.50 stddev:34.45 (25.1%) stddev-sans-first:36.33 06:10:45 INFO - PROCESS | 1669 | Values: 138.0 137.0 135.0 135.0 134.0 136.0 137.0 140.0 246.0 149.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:164.50 stddev:26.91 (16.4%) stddev-sans-first:28.12 06:10:45 INFO - PROCESS | 1669 | Values: 163.0 218.0 235.0 166.0 164.0 164.0 161.0 165.0 164.0 161.0 06:10:45 INFO - PROCESS | 1669 | 06:10:45 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.17 (3.2%) stddev-sans-first:2.24 06:10:45 INFO - PROCESS | 1669 | Values: 69.0 73.0 66.0 67.0 66.0 66.0 67.0 68.0 66.0 67.0 06:10:45 INFO - PROCESS | 1669 | -------- Summary: end -------- 06:10:45 INFO - PROCESS | 1669 | 06:10:46 INFO - TEST-INFO | 1669: exit 0 06:10:46 INFO - TEST-OK | kraken | took 60076ms 06:10:46 INFO - SUITE-END | took 1177s 06:10:46 INFO - Completed test suite (00:19:37) 06:10:46 INFO - javascript benchmark 06:10:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.849821811474, 3351.8156501392423, 3360.3783300089476, 3379.9831000844993, 3417.8803741869024, 5542.418046105151, 5583.804566840161, 5617.101263797793, 5668.3198016238, 5695.487406133447, 5583.553315734752, 5630.127330263806, 5716.1425786131995, 5723.112917497784, 5753.626014309078, 5114.181730923053, 5143.974280128594, 5170.482951704818, 5184.844454666347, 5286.497782710637, 5297.337832770903, 5297.417284098746, 5298.205269209611, 5317.12267475868, 5343.893122137555, 4242.40606315113, 5111.718855462948, 5152.690838549662, 5188.662736922107, 5195.766190521435, 5169.767360468788, 5192.7403629818355, 5209.0363282792805, 5209.479052094798, 5211.1922651988625, 5092.083424983502, 5115.386153661547, 5132.717700526469, 5172.5344718975475, 5212.50481204285, 5578.0517312057045, 5676.631018983753, 5676.971615141939, 5713.914291285634, 5722.227499287626, 6211.968940155315, 6222.12890195373, 6227.972384556543, 6243.500519958392, 6247.094171345175, 6102.822530596007, 6109.951287404306, 6204.565680402374, 6223.315435302113, 6225.688715564203, 5788.957987562237, 5810.564207684441, 5828.53371730269, 5829.475347218686, 5841.85395365115, 5624.91562626561, 6311.274203466568, 6312.684365781693, 6327.715252813542, 6336.619802811846, 5917.733701983421, 5975.551833612496, 5988.730507127189, 6056.031035034462, 6060.545459090586, 6188.164597779242, 6201.906971395432, 6205.968970155211, 6206.565540412218, 6210.813675589739, 6072.605280656831, 6142.447179753844, 6143.047827586731, 6143.754249829957, 6163.3528479509005, 5555.638868477249, 6059.36376680451, 6107.84730381744, 6112.358202388685, 6180.66006369645, 4568.428946381702, 4599.827044103732, 4890.510948905152, 4909.3126962225315, 4932.26016097581, 4396.736195828261, 4399.340098985178, 4419.513853476115, 4421.005273813366, 4452.220861349251, 5543.805966791224, 5703.401142879938, 5723.996601189634, 5776.537876969833, 5783.103618939072, 6128.717018009172, 6159.4456498915315, 6213.37866213375, 6226.1905952225925, 6287.056941458726, 5685.147227915846, 5721.456461636138, 5729.88540229193, 5731.0543760279925, 5763.4812866842185, 4569.54304569547, 5283.286756287929, 5334.946650533522, 5387.595930305164, 5413.377461591967, 6263.812085637438, 6304.936950630435, 6315.55791094619, 6318.494520438446, 6324.1778568786685, 6193.0, 6237.126802247598, 6238.968805155945, 6311.431971122621, 6318.399752023551, 6291.433770960635, 6301.684915754102, 6303.9598466252555, 6306.936930630635, 6311.116443697794, 6119.571629985859, 6126.632402055891, 6188.381161883775, 6197.473214776614, 6202.0, 6104.145419641165, 6125.662799505599, 6131.346082862053, 6137.8979746733885, 6256.0303153011355, 6062.272527296753, 6089.69551522431, 6104.448125162133, 6111.083337499233, 6141.07883817431, 4788.970371370033, 6026.578139530191, 6071.998120310276, 6095.05526643371, 6097.390260973867, 5794.942050579441, 5829.271341082364, 5850.824475265749, 5901.026330655393, 5958.463738263577, 6106.297775755845, 6108.726966420949, 6125.663088529996, 6137.478314343152, 6139.216166136355, 5893.970530147321, 5903.439173278532, 5904.704764761659, 5915.08316210988, 5923.703814809329], "value": 5653.469915781849, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.16691041043805, 485.12192930795266, 522.8039485193052, 525.2200482283811, 529.1242992846837, 27321.0, 32747.345053098954, 42041.0, 42919.14161716764, 43021.56978430215, 43946.0, 44387.334189987094, 44428.0, 44439.55560444394, 44489.0, 9738.610455581767, 9920.45437500937, 9929.106380425761, 9937.055979681954, 9963.402195868235, 7451.850962980737, 8739.563021848913, 8922.821543569125, 8997.640094396216, 9012.729618111467, 11707.716995185941, 11904.64286071415, 11938.582149624764, 12179.025677945787, 12184.878151218463, 11874.881251187508, 11962.58130965412, 11992.100592455601, 12008.459619317151, 12090.093243006722, 8601.182887625666, 11809.055275577934, 11858.466369013415, 11882.702932426671, 11934.343611101385, 31259.687403126132, 32766.017019489453, 32821.0, 33607.15982100467, 41105.383419248734, 2985.208919636298, 3713.7771733696063, 3719.2800621873257, 4243.448351714257, 4427.579379958899, 32033.51949720756, 32221.0, 33507.83246083778, 33546.832265838515, 33565.83217083923, 3370.5112758650102, 3389.068006298263, 3417.743669224818, 3419.692227699494, 3423.127102588824, 467310.0, 476909.0, 477752.0, 478283.0, 479533.0, 58778.0, 67916.98124528136, 69081.30918690749, 69255.30744692589, 69643.30356696369, 71129.53139920338, 71175.64412177906, 71233.0, 71329.2867071333, 71381.14475420925, 17418.216180271917, 17418.651626967643, 17511.299548018193, 17610.735838962424, 17621.7356739649, 13151.736965260641, 13219.074664773374, 13237.404316805767, 13243.404046817916, 13254.801177982337, 18267.63464730698, 19521.822993764905, 20946.47633809167, 20982.37052888416, 21871.781282187294, 8792.164744349277, 8822.2060014599, 8863.556822158995, 8889.333300002525, 8937.150970657776, 857.7255278311005, 900.8513595256841, 959.4147569982305, 1046.4506134279563, 1058.6771034834449, 25569.872150639127, 29899.70100299013, 30608.0, 30815.52933673457, 30984.53523197154, 1060.6022741471947, 1079.4818487126224, 1136.8063533290012, 1143.3597185575939, 1147.5180424221846, 14876.561719140429, 15675.0, 16147.0, 16293.348266069224, 16478.44584089468, 714688.0, 720110.0, 720191.0, 720637.0, 723669.0, 59789.70105149446, 60239.0, 60398.0, 60424.0, 60509.39490605125, 1381.9239941803298, 1407.8310602727738, 1424.7079348733691, 1427.186503692885, 1428.0788891165141, 29825.403491930472, 30264.94072707533, 30548.504690141195, 30834.074977750704, 31181.688183118786, 52640.47359526356, 53342.199867002804, 53382.66543336448, 53405.0, 53472.73263633657, 609410.9529452324, 610621.0, 611575.0, 612146.0, 615702.0, 28048.719512805426, 28693.56959645565, 28813.423731524832, 29831.552526712552, 44966.77516612396, 39076.2184756309, 49495.50504494909, 50301.47889647836, 50489.0, 52060.0, 40468.39297410482, 40502.392464113655, 40825.0, 41790.58209417989, 42041.57958420377, 40276.0, 40344.798276008434, 40678.38982415325, 41630.0, 42091.579084208766, 38739.612603873604, 38909.80545097256, 38993.02517436973, 39856.40215396713, 39921.80039099902, 33357.66642333545, 35521.93434196978, 36180.45729314115, 36379.818100909324, 36439.63560364363, 20214.898925505277, 24709.752902470744, 25472.108476203244, 26056.34859128537, 26590.601140983286], "value": 21073.337828664706, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.7775334799123, 381.5802617121167, 386.4608870625477, 397.8289335585697, 404.8684177642267, 16030.59923501913, 16855.494335169933, 19003.61992760144, 21148.154073837028, 21156.682649760267, 17005.0, 19786.0, 19877.701834472493, 20683.276085337016, 20895.268665596708, 5337.5463085637575, 5861.0915308127205, 6004.18943442634, 6119.571629985903, 6144.5698801083945, 5987.55093367997, 6097.390260973912, 6109.847253818646, 6112.502436902965, 6117.418845209698, 5042.420121685998, 5819.185314056035, 5973.163757074013, 6020.096985452174, 6050.636961782285, 5953.3748956359605, 5967.194428752106, 6006.42938920804, 6009.308929473123, 6009.707912798725, 5070.569001634865, 5333.5199832015305, 5388.757505912205, 5396.541293990015, 5428.185772134172, 12934.965202783755, 13064.477420903153, 13221.603351899459, 18124.90937545317, 24820.751792482024, 2128.670056141285, 2170.674398840171, 2176.3144609447972, 2195.1438938813876, 2841.247069526577, 9240.399374040666, 9546.61813527465, 9935.453550054744, 9966.252531060127, 10132.594696212143, 1739.90430526321, 1770.1768677564864, 1799.109440826799, 1840.8245776036365, 1860.479065861561, 101264.98735012629, 111341.0, 111544.0, 131540.0, 138314.92527612095, 10965.780684386267, 11287.29105014807, 11404.372759498141, 11451.427428628534, 11686.766264674829, 14473.131612103307, 14529.709405811824, 14536.563903082924, 14543.563693089225, 14552.708945821236, 6615.76844810439, 8491.61787719554, 8559.186877246651, 8634.913650863411, 8646.567671616394, 8477.27943124829, 8479.448835825651, 8501.617427215666, 8657.091005444337, 8684.305255579538, 4343.348497725303, 4449.3993310902615, 4459.799309031101, 4522.8643140705835, 4528.88677783058, 1867.8972656504022, 1916.453810663982, 1916.5208697825544, 1919.9136038878285, 1927.4892153579312, 715.2171335994002, 837.5267973595021, 929.7350255177242, 934.275936149476, 939.7415710679618, 20024.799752002586, 21013.68479472809, 21095.050722717515, 21123.366299011053, 21295.254666086614, 808.4138999225514, 818.3003531980071, 908.1191244492832, 954.5609019850947, 996.5465713100522, 10542.631007914686, 11035.668929932115, 12017.399130043463, 12050.75898482043, 12131.75736485283, 613021.0, 621091.0, 623684.0, 630806.0, 630986.0, 91118.0, 126709.36645316899, 148850.0, 152139.0, 155633.0, 944.6929747831982, 980.9362391444533, 1006.225206590921, 1009.0565321424344, 1009.9545520451597, 18318.450446486626, 18662.440126796217, 18720.53198670044, 18915.26539733493, 18990.715139273198, 44775.0, 57808.71095644495, 58351.708241458524, 58885.705571471866, 59312.51718707067, 85138.57430713055, 85387.0, 87253.56373218093, 88097.1190288089, 88433.0, 5352.946470535245, 6371.840703982438, 6559.0, 6955.956606509064, 7659.808504787202, 10645.574177032831, 12259.816102758645, 12524.49902003946, 12944.676383090122, 13038.608841734764, 10877.619283325046, 10884.455777211266, 10920.890791092304, 10953.56185752561, 11003.834942476029, 9145.451272923647, 10662.73343166427, 10693.465326733476, 10897.12822974176, 10973.780524389307, 10823.188260880308, 11143.33140011602, 11277.266977646426, 11444.828327574927, 11488.885111148782, 7645.770626881203, 7833.686652533835, 7897.723579674684, 8074.475159114639, 8226.712065077932, 9931.354461959949, 10843.1867609928, 11246.943765281449, 11250.606228781953, 11260.493277802683], "value": 9369.234353559197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.529223586806, 1589.8410158984102, 1803.8737288389818, 1945.7081437784339, 1951.3853136262085, 8445.91554084459, 8891.510966896818, 8901.376903616749, 8901.688440904562, 8914.50970196639, 7818.257265559777, 8037.27664510194, 8426.410151289412, 8651.394402391836, 8691.91308086919, 2321.3152120124605, 2345.29641107667, 2347.495288512969, 2347.530493901218, 2354.0583766493455, 2301.1255722825304, 2317.1194945920524, 2348.0842471436154, 2349.3186975777003, 2353.176388264111, 2063.411927600634, 2144.9463763405884, 2146.6672665736837, 2150.666646669769, 2329.813614910803, 2252.2117258959315, 2337.0, 2341.555104530134, 2343.7304709958403, 2344.167820423742, 2309.2841219222096, 2314.4329639238326, 2314.7338056123594, 2317.165820304689, 2322.198841399714, 8779.03430622631, 8827.646894124227, 8833.69082082124, 8845.115488451167, 8861.335399845037, 5161.3806343238675, 5326.36083669962, 5327.254184414185, 5332.466753324676, 5333.1733581294575, 2983.4928062229474, 3369.534252600127, 3433.759636825423, 3571.821408929543, 3595.496630471736, 886.898006729228, 1042.8957104289586, 1045.8326667733127, 1062.3678911047914, 1066.951987160572, 11562.0, 11732.35472049037, 11755.7061073473, 11757.823632645517, 11790.705232369173, 4489.191945449785, 4584.633229341644, 4684.461286951976, 4737.928931066037, 4738.289256611536, 9840.507974601242, 9843.704688859345, 9847.950760246153, 9850.458224797561, 9860.605575776888, 5272.393674727456, 5272.657277276965, 5276.683398996072, 5282.339707536558, 5282.498162674539, 4350.3039513678295, 4488.685791994529, 4679.064187162581, 4850.733209673432, 4857.951420485821, 3222.46829273172, 3743.2139250757646, 3777.301199278142, 3911.3155197840833, 3952.3083460394314, 1043.5147656339839, 1225.1791299829135, 1229.4221715793562, 1232.2852745407642, 1240.3054289597853, 660.4617236951868, 670.0953712488102, 862.094800459515, 862.1464749897556, 895.2748273898163, 5632.211490391349, 5736.541076713853, 5959.314678811906, 6037.18498002764, 6157.0, 386.4222986634999, 445.56557356577076, 450.4639479019956, 923.8798956135794, 1055.2507719519228, 3856.0745421098736, 4032.862582831104, 4035.616616421435, 4080.1227736036894, 4081.3877918311896, 9290.1174388433, 10126.848097278547, 10142.543585538668, 10964.56141754321, 10984.121270298358, 10422.635207767691, 10797.46012699362, 10811.783764324828, 10812.405317707444, 10824.0, 7977.920220797834, 8048.114707382183, 8196.42625016255, 8298.460600061097, 8302.211289927447, 5947.464728174485, 6029.788957386646, 6161.476274516716, 6229.781957631461, 6250.218722659668, 10015.649452269136, 10045.296829221885, 10047.246456515644, 10049.397036177852, 10055.446950417798, 9590.472524011246, 10230.590776368861, 10235.84646230322, 10246.0, 10263.538140783832, 6965.477589180933, 7178.353948144692, 7187.101612298463, 7596.544207347577, 7743.303102720783, 7771.106322772954, 7909.288164065261, 7986.600669966595, 7999.800004999689, 8183.795405114682, 7210.747623833141, 7560.546367217985, 7591.0, 7616.657250423855, 7651.0, 7345.55926644403, 7445.478816482794, 7451.776446706607, 7492.550446973199, 7535.058117735283, 6980.336867997532, 7104.0, 7110.431165506644, 7120.323569260912, 7120.537165084253, 4253.957460425356, 4811.230203167476, 4905.681130726492, 4911.165101932608, 5006.69959802413, 4920.975395123001, 4998.375203099613, 5038.773255203451, 5083.669561478492, 5211.478852114758], "value": 4489.718991484639, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [242.5161802204602, 305.51880787759274, 309.3426468753898, 314.09697120777776, 316.3893685187587, 4735.834245801398, 5350.438203988583, 5380.7847686092555, 5471.015217260897, 5500.477454641813, 5135.460776618448, 5200.531952124308, 5377.327834020747, 5406.83779486616, 5436.9184462233, 3071.539269109629, 3851.0372406898277, 3931.7811478441777, 4104.199681062194, 4121.1345617420375, 4124.381342798574, 4151.252774500589, 4152.73007254529, 4169.854055108071, 4199.895002624943, 4093.3041382964966, 4103.835846566134, 4106.79466026699, 4129.648979836717, 4131.6281534661875, 3403.5915690117095, 3718.888433347004, 3894.493715816954, 4112.979435102805, 4143.337066069429, 2862.398896231794, 3143.402753476832, 3596.514470546469, 3601.8559257629663, 3639.3813051780994, 4659.8564660256325, 5258.6581872178185, 5285.233641122054, 5304.549113325372, 5352.143657014898, 4583.449986001668, 4723.362346083303, 4748.643851711101, 4768.47546769855, 4801.615870730333, 4972.662848461217, 5664.272396918938, 5691.0, 5707.258056452634, 5731.512821410185, 3340.2818394045153, 3721.702263818915, 3783.2622638585485, 3992.6207010333974, 4000.139969906455, 5650.886982260373, 5986.138940383428, 5993.760249589967, 6001.399860014008, 6035.366286539938, 3985.980070099689, 3992.4610177626228, 4003.3594624859884, 4010.61899119589, 4022.35642297231, 3442.397580423416, 4044.737092089005, 4098.631123198927, 4130.0, 4130.194612050621, 4443.622292105143, 4738.218193998021, 4750.263709125091, 4751.239801631722, 4762.880927976828, 5665.405132461115, 5684.744186511617, 5698.287714035746, 5704.344000440003, 5709.601147718836, 1233.9383030848421, 1235.610782603495, 1377.9242141682103, 1410.8307003159482, 1420.4247279851677, 766.9348105411102, 831.5925196653596, 871.825634873028, 882.9536998656613, 893.41927746965, 4459.063596644676, 4579.138038725796, 4837.879053023632, 5011.974940125276, 5349.0, 4833.622417610964, 5263.894722105537, 5615.904898544826, 5873.3245676746865, 5881.882362352814, 5119.360079990001, 5334.866628334245, 5362.27609272751, 5410.458954104637, 5423.698312405073, 2974.301039255773, 3032.7725420593542, 3094.05631282461, 3108.4870996285813, 3126.2340726522148, 5345.33183352081, 5817.825465236049, 6122.54080943931, 6193.535484838655, 6201.6899155042065, 4961.940794570285, 5594.636348637326, 5963.403659634002, 6054.636721796706, 6060.969695151584, 5544.833654990357, 5941.435563621536, 6039.071050540484, 6049.516038716892, 6058.909116363258, 5610.354809196831, 5897.262842144732, 6019.428154325332, 6026.758929642765, 6032.698365081641, 4398.362237475612, 4848.733319667311, 5312.389075256395, 5457.781688732565, 5856.59003869725, 5119.360079990001, 6114.969425152845, 6125.356837532173, 6183.628982261079, 6186.938130618816, 3685.944710829286, 3875.534935807721, 3904.0239940014994, 3918.746720635589, 3972.741771784825, 5834.920539700169, 5852.590318677822, 5857.5313974881055, 5862.944669959277, 5890.14592884038, 4693.530646935279, 4906.681065730716, 5650.26546548946, 5793.333766616893, 5814.034842230409, 5043.9243411348125, 5156.762377029561, 5175.404828444777, 5366.463353664602, 5673.659580425187, 5416.566674666096, 5633.408492108433, 5650.344338161058, 5709.514691251124, 5711.915150969783, 5280.973595132, 5491.693488734821, 5719.513841323368, 5743.368229494836, 5776.967092276588, 5291.735413229246, 5574.832755017356, 5597.63615365, 5609.915851262316, 5639.210510528611], "value": 4155.497358716969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.7798571435711, 744.4565467208939, 764.6941223510595, 822.7719484330157, 825.7316372179043, 14944.40222391103, 16119.919400402996, 16129.51611451657, 16136.677266454664, 16141.354345826183, 14597.78103328451, 14803.40786368544, 14813.0, 14813.481528146516, 14861.405543778235, 716.1012928774381, 736.3740820302727, 886.0209468537254, 914.0, 914.1407077347305, 873.6199753107387, 896.7175339767984, 902.3728508686432, 906.3112034853526, 907.120093509295, 964.6189755046752, 966.0629189686028, 966.6133546581353, 967.4195482710388, 968.9612415503371, 754.2645920227789, 962.451402700461, 963.2438535749438, 963.5278713430368, 966.3718582921088, 693.3343989769777, 709.2092317066486, 710.2187593646998, 714.4141803720951, 715.906932098829, 15040.247987600686, 15076.02005869615, 15104.924475377662, 15112.0, 15120.773188402183, 13708.24604646744, 13951.58145255644, 14090.859091409056, 14163.858361416356, 14165.645858853508, 12759.872401275961, 12856.678583035498, 12882.1626594272, 13032.348382580833, 13089.149205301625, 4571.748553829538, 4614.976925115387, 4743.217369134123, 4805.303231031516, 4822.710637361734, 96059.51970240104, 96645.0, 96862.0, 97273.0272697271, 99885.5005724974, 11007.504662290215, 11050.336979781077, 11128.777424451466, 11171.59716289508, 11300.265482743594, 21911.452213694465, 21919.561608768054, 21924.23265185711, 21930.342089737333, 21968.45078873009, 7960.203979602109, 8007.479513831699, 8118.2693557580105, 8125.9187408126345, 8254.0, 4434.733915965053, 4537.841175558906, 4571.428571428572, 4683.95316046842, 4709.152352576569, 3400.4219282721997, 3462.117160124152, 3506.333796578642, 3508.1054331145906, 3513.0865974846724, 589.4253103224322, 604.6667099046591, 627.4948666323562, 633.7591715148214, 644.4779728419995, 421.2806335106878, 581.5522048022999, 598.8862116197909, 614.2598169206096, 637.7082236548058, 8631.43895646781, 9093.272538197061, 9138.54307284633, 9535.332526723201, 9684.225261979025, 578.6123297390758, 599.9850003749941, 618.8483821463681, 660.9636469994197, 669.1167658690482, 4351.6301114405, 4640.976795116003, 4665.930011049837, 4728.196206644879, 4750.904981900412, 88651.11348886428, 90231.0, 136522.95215571774, 144090.0, 152465.0, 105856.47071764746, 114799.0, 116315.0, 126556.0, 128362.0, 105615.0, 110621.0, 114340.85659143468, 117786.0, 117843.0, 9604.471754053595, 9791.804163916824, 10209.591616335263, 10229.641962531276, 10251.948740256252, 57292.0, 64779.67610162107, 66990.0, 67742.0, 69295.61408771898, 94498.0, 96737.51631241798, 97646.51176744071, 97690.0, 98349.0, 13188.934055330046, 13547.25490098054, 13629.659258518219, 13794.724105517633, 14036.508722194674, 14238.928805355907, 15469.922650386676, 15583.76624350613, 16035.358585656442, 16054.036757794569, 14243.64390890236, 14498.782518262446, 14598.051126676732, 14632.122072675673, 14917.403303867724, 14171.787423188867, 14380.928095359875, 14419.279036048365, 14680.265986700835, 14842.48051318242, 13991.650208744863, 14218.857811421753, 14499.275036248357, 14647.92676036613, 14656.267186640838, 11378.43107844588, 11459.885401145882, 11538.82691759641, 11675.532978680758, 12157.08821838348, 10508.579656813641, 11136.665900023012, 11157.944210278896, 11575.10622344414, 11719.648410547697], "value": 7969.606644055379, "unit": "score"}], "name": "dromaeo_css", "value": 7413.328812279595, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 109.0, 110.0, 111.0, 111.0, 111.0, 109.0, 110.0, 111.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 116.0, 196.0, 137.0, 92.0, 153.0, 136.0, 178.0, 113.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 181.0, 186.0, 236.0, 252.0, 181.0, 181.0, 185.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 58.0, 89.0, 59.0, 57.0, 64.0, 61.0, 84.0, 58.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 94.0, 73.0, 99.0, 101.0, 132.0, 69.0, 69.0, 126.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 91.0, 109.0, 116.0, 136.0, 85.0, 88.0, 85.0], "value": 97.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 95.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 94.0, 88.0, 87.0, 84.0, 84.0, 82.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 52.0, 53.0, 55.0, 52.0, 51.0, 53.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 80.0, 77.0, 75.0, 71.0, 75.0, 74.0, 71.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 84.0, 83.0, 82.0, 82.0, 87.0, 96.0, 103.0, 80.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 135.0, 135.0, 134.0, 136.0, 137.0, 140.0, 246.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 218.0, 235.0, 166.0, 164.0, 164.0, 161.0, 165.0, 164.0, 161.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 66.0, 67.0, 66.0, 66.0, 67.0, 68.0, 66.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1487.6, "alertThreshold": 2.0}]} 06:10:47 INFO - Return code: 0 06:10:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:10:47 INFO - # TBPL SUCCESS # 06:10:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:10:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:10:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:10:47 INFO - 2016-07-20 06:10:47.126 screenresolution[1673:9918] starting screenresolution argv=screenresolution get 06:10:47 INFO - 2016-07-20 06:10:47.129 screenresolution[1673:9918] Display 0: 1600x1200x32@60 06:10:47 INFO - 2016-07-20 06:10:47.135 screenresolution[1674:9921] starting screenresolution argv=screenresolution list 06:10:47 INFO - Available Modes on Display 0 06:10:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:10:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:10:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:10:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:10:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:10:47 INFO - Intel Iris: 06:10:47 INFO - Chipset Model: Intel Iris 06:10:47 INFO - Type: GPU 06:10:47 INFO - Bus: Built-In 06:10:47 INFO - VRAM (Dynamic, Max): 1536 MB 06:10:47 INFO - Vendor: Intel (0x8086) 06:10:47 INFO - Device ID: 0x0a2e 06:10:47 INFO - Revision ID: 0x0009 06:10:47 INFO - Displays: 06:10:47 INFO - Display: 06:10:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:10:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:10:47 INFO - Display Serial Number: 1600x1200 60 06:10:47 INFO - Main Display: Yes 06:10:47 INFO - Mirror: Off 06:10:47 INFO - Online: Yes 06:10:47 INFO - Rotation: Supported 06:10:47 INFO - Return code: 0 06:10:47 INFO - Running post-action listener: _resource_record_post_action 06:10:47 INFO - [mozharness: 2016-07-20 13:10:47.371892Z] Finished run-tests step (success) 06:10:47 INFO - Running post-run listener: _resource_record_post_run 06:10:47 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37032960; Write bytes: 703876608; Read time: 242; Write time: 1938 06:10:47 INFO - TinderboxPrint: CPU usage
53.3% 06:10:47 INFO - TinderboxPrint: I/O read bytes / time
37,032,960 / 242 06:10:47 INFO - TinderboxPrint: I/O write bytes / time
703,876,608 / 1,938 06:10:47 INFO - TinderboxPrint: CPU idle
2,264.9 (46.7%) 06:10:47 INFO - TinderboxPrint: CPU system
967.9 (19.9%) 06:10:47 INFO - TinderboxPrint: CPU user
1,620.9 (33.4%) 06:10:47 INFO - TinderboxPrint: Swap in / out
444,829,696 / 0 06:10:47 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401715712; Write bytes: 395625984; Read time: 30759; Write time: 783 06:10:47 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34919424; Write bytes: 292321280; Read time: 219; Write time: 1112 06:10:47 INFO - Running post-run listener: _upload_blobber_files 06:10:47 INFO - Blob upload gear active. 06:10:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:10:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:10:48 INFO - (blobuploader) - INFO - Open directory for files ... 06:10:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:10:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:10:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:48 INFO - (blobuploader) - INFO - Done attempting. 06:10:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:10:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:10:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:49 INFO - (blobuploader) - INFO - Done attempting. 06:10:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:10:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:10:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:54 INFO - (blobuploader) - INFO - Done attempting. 06:10:54 INFO - (blobuploader) - INFO - Iteration through files over. 06:10:54 INFO - Return code: 0 06:10:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:10:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:10:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc3fed5fbee9052fd3a998fa34f763aeb2006a34a962d9eb767f40e69aca1523791cd63c46ecad1789d1a6399867f5817174786ae15df45fa5c210a0053417cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7110e9988742a7f106ee4cd5d3290da72553b3200ce346f1a4d05589ef6ab62cded37ac4774b691a8887e9f13501ffdfdfa5ba1b1ea0aa7ffa298ceebddd9fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d68516264c1cffb896d268340618748e812325e1143458c1d8630beab3c1f34e388bfef661712a7e922d4392668dd24822a0b2f5bcb7171394bff3acd313760"} 06:10:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:10:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:10:54 INFO - Contents: 06:10:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc3fed5fbee9052fd3a998fa34f763aeb2006a34a962d9eb767f40e69aca1523791cd63c46ecad1789d1a6399867f5817174786ae15df45fa5c210a0053417cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7110e9988742a7f106ee4cd5d3290da72553b3200ce346f1a4d05589ef6ab62cded37ac4774b691a8887e9f13501ffdfdfa5ba1b1ea0aa7ffa298ceebddd9fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d68516264c1cffb896d268340618748e812325e1143458c1d8630beab3c1f34e388bfef661712a7e922d4392668dd24822a0b2f5bcb7171394bff3acd313760"} 06:10:54 INFO - Running post-run listener: copy_logs_to_upload_dir 06:10:54 INFO - Copying logs to upload dir... 06:10:54 INFO - mkdir: /builds/slave/test/build/upload/logs 06:10:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.372683 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-20 06:10:54.431401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 06:10:54.435237) ========= 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.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc3fed5fbee9052fd3a998fa34f763aeb2006a34a962d9eb767f40e69aca1523791cd63c46ecad1789d1a6399867f5817174786ae15df45fa5c210a0053417cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7110e9988742a7f106ee4cd5d3290da72553b3200ce346f1a4d05589ef6ab62cded37ac4774b691a8887e9f13501ffdfdfa5ba1b1ea0aa7ffa298ceebddd9fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d68516264c1cffb896d268340618748e812325e1143458c1d8630beab3c1f34e388bfef661712a7e922d4392668dd24822a0b2f5bcb7171394bff3acd313760"} build_url:https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011019 build_url: 'https://queue.taskcluster.net/v1/task/Q0ZTMEXOR2eBabHSV7FRMw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc3fed5fbee9052fd3a998fa34f763aeb2006a34a962d9eb767f40e69aca1523791cd63c46ecad1789d1a6399867f5817174786ae15df45fa5c210a0053417cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7110e9988742a7f106ee4cd5d3290da72553b3200ce346f1a4d05589ef6ab62cded37ac4774b691a8887e9f13501ffdfdfa5ba1b1ea0aa7ffa298ceebddd9fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d68516264c1cffb896d268340618748e812325e1143458c1d8630beab3c1f34e388bfef661712a7e922d4392668dd24822a0b2f5bcb7171394bff3acd313760"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 06:10:54.467482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 06:10:54.467851) ========= 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.1UCwDmNLf3/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.gfMJWVAITT/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 06:10:54.517826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 06:10:54.518143) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 06:10:54.518486) ========= ========= Total master_lag: 0.18 =========