builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0246 starttime: 1491585584.28 results: success (0) buildid: 20170407085322 builduid: b6dd34296d0347cfb2af06886979953e revision: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.280352) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.280780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.281057) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.305875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.306214) ========= 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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 --2017-04-07 10:19:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.03M=0.001s 2017-04-07 10:19:44 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.474417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.474731) ========= 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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.021461 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:44.523351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 10:19:44.523793) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de --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 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 2017-04-07 10:19:44,484 truncating revision to first 12 chars 2017-04-07 10:19:44,484 Setting DEBUG logging. 2017-04-07 10:19:44,485 attempt 1/10 2017-04-07 10:19:44,485 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22df0c5cfbf8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 10:19:45,472 unpacking tar archive at: mozilla-central-22df0c5cfbf8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.261033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 10:19:45.808382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:45.808703) ========= script_repo_revision: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:45.809100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:45.809384) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:19:46.124248) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-07 10:19:46.124669) ========= /tools/buildbot/bin/python -u 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', '-u', '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:19:46 INFO - MultiFileLogger online at 20170407 10:19:46 in /builds/slave/test 10:19:46 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 10:19:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:19:46 INFO - {'append_to_log': False, 10:19:46 INFO - 'base_work_dir': '/builds/slave/test', 10:19:46 INFO - 'blob_upload_branch': 'Firefox', 10:19:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:19:46 INFO - 'branch': 'Firefox', 10:19:46 INFO - 'buildbot_json_path': 'buildprops.json', 10:19:46 INFO - 'config_files': ('talos/mac_config.py',), 10:19:46 INFO - 'default_actions': ('clobber', 10:19:46 INFO - 'read-buildbot-config', 10:19:46 INFO - 'download-and-extract', 10:19:46 INFO - 'populate-webroot', 10:19:46 INFO - 'create-virtualenv', 10:19:46 INFO - 'install', 10:19:46 INFO - 'run-tests'), 10:19:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:19:46 INFO - 'download_minidump_stackwalk': True, 10:19:46 INFO - 'download_symbols': 'ondemand', 10:19:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:19:46 INFO - 'tooltool.py': '/tools/tooltool.py', 10:19:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:19:46 INFO - '/tools/misc-python/virtualenv.py')}, 10:19:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:19:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:19:46 INFO - 'gecko_profile': False, 10:19:46 INFO - 'gecko_profile_interval': 0, 10:19:46 INFO - 'installer_path': 'installer.exe', 10:19:46 INFO - 'log_level': 'info', 10:19:46 INFO - 'log_name': 'talos', 10:19:46 INFO - 'log_to_console': True, 10:19:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:19:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:19:46 INFO - 'opt_config_files': (), 10:19:46 INFO - 'pip_index': False, 10:19:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:46 INFO - 'cmd': ('bash', 10:19:46 INFO - '-c', 10:19:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:46 INFO - 'enabled': True, 10:19:46 INFO - 'halt_on_failure': False, 10:19:46 INFO - 'name': 'check_screen_resolution'},), 10:19:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:46 INFO - 'cmd': ('bash', 10:19:46 INFO - '-c', 10:19:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:46 INFO - 'enabled': True, 10:19:46 INFO - 'halt_on_failure': False, 10:19:46 INFO - 'name': 'check_screen_resolution'},), 10:19:46 INFO - 'run_cmd_checks_enabled': True, 10:19:46 INFO - 'suite': 'dromaeojs', 10:19:46 INFO - 'system_bits': '32', 10:19:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:19:46 INFO - 'title': 't-yosemite-r7-0246', 10:19:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:19:46 INFO - 'use_talos_json': True, 10:19:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:19:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:19:46 INFO - 'work_dir': 'build'} 10:19:46 INFO - [mozharness: 2017-04-07 17:19:46.205414Z] Running clobber step. 10:19:46 INFO - Running pre-action listener: _resource_record_pre_action 10:19:46 INFO - Running main action method: clobber 10:19:46 INFO - rmtree: /builds/slave/test/build 10:19:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:19:46 INFO - Running post-action listener: _resource_record_post_action 10:19:46 INFO - [mozharness: 2017-04-07 17:19:46.368318Z] Finished clobber step (success) 10:19:46 INFO - [mozharness: 2017-04-07 17:19:46.368486Z] Running read-buildbot-config step. 10:19:46 INFO - Running pre-action listener: _resource_record_pre_action 10:19:46 INFO - Running main action method: read_buildbot_config 10:19:46 INFO - Using buildbot properties: 10:19:46 INFO - { 10:19:46 INFO - "project": "", 10:19:46 INFO - "product": "firefox", 10:19:46 INFO - "script_repo_revision": "production", 10:19:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:19:46 INFO - "repository": "", 10:19:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:19:46 INFO - "buildid": "20170407085322", 10:19:46 INFO - "pgo_build": "False", 10:19:46 INFO - "basedir": "/builds/slave/test", 10:19:46 INFO - "buildnumber": 6, 10:19:46 INFO - "slavename": "t-yosemite-r7-0246", 10:19:46 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:19:46 INFO - "platform": "yosemite_r7", 10:19:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:19:46 INFO - "branch": "mozilla-central", 10:19:46 INFO - "repo_path": "mozilla-central", 10:19:46 INFO - "revision": "22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de", 10:19:46 INFO - "stage_platform": "macosx64", 10:19:46 INFO - "builduid": "b6dd34296d0347cfb2af06886979953e", 10:19:46 INFO - "slavebuilddir": "test" 10:19:46 INFO - } 10:19:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 10:19:46 INFO - Running post-action listener: _resource_record_post_action 10:19:46 INFO - [mozharness: 2017-04-07 17:19:46.372453Z] Finished read-buildbot-config step (success) 10:19:46 INFO - [mozharness: 2017-04-07 17:19:46.372606Z] Running download-and-extract step. 10:19:46 INFO - Running pre-action listener: _resource_record_pre_action 10:19:46 INFO - Running main action method: download_and_extract 10:19:46 INFO - mkdir: /builds/slave/test/build/tests 10:19: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:46 INFO - trying https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:19:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:19:47 INFO - Downloaded 1325 bytes. 10:19:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:19:47 INFO - Using the following test package requirements: 10:19:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:19:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:19:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:19:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:19:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'jsshell-mac.zip'], 10:19:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:19:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:19:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:19:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:19:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:19:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:19:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:19:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:19:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:19:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:19:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:19:49 INFO - Expected file size: 52519772 10:19:49 INFO - Obtained file size: 52519772 10:20:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:20:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:20:07 INFO - Expected file size: 52519772 10:20:07 INFO - Obtained file size: 52519772 10:20:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:20:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:20:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:20:24 INFO - Expected file size: 14404138 10:20:24 INFO - Obtained file size: 14404138 10:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:24 INFO - trying https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:20:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:20:26 INFO - Downloaded 70887646 bytes. 10:20:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:20:26 INFO - mkdir: /builds/slave/test/properties 10:20:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:20:26 INFO - Writing to file /builds/slave/test/properties/build_url 10:20:26 INFO - Contents: 10:20:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:20:27 INFO - Running post-action listener: _resource_record_post_action 10:20:27 INFO - Running post-action listener: set_extra_try_arguments 10:20:27 INFO - [mozharness: 2017-04-07 17:20:27.396239Z] Finished download-and-extract step (success) 10:20:27 INFO - [mozharness: 2017-04-07 17:20:27.396359Z] Running populate-webroot step. 10:20:27 INFO - Running pre-action listener: _resource_record_pre_action 10:20:27 INFO - Running main action method: populate_webroot 10:20:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:20:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:20:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:20:27 INFO - '--tppagecycles', 10:20:27 INFO - '7'], 10:20:27 INFO - 'tests': ['tsvgm']}}, 10:20:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:20:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:20:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:20:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:20:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['damp', 'tps']}, 10:20:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'tests': ['damp', 'tps']}, 10:20:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['dromaeo_dom']}, 10:20:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:20:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['basic_compositor_video']}, 10:20:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:20:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'other-e10s': {'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['a11yr', 10:20:27 INFO - 'ts_paint', 10:20:27 INFO - 'tpaint', 10:20:27 INFO - 'sessionrestore', 10:20:27 INFO - 'sessionrestore_no_auto_restore', 10:20:27 INFO - 'tabpaint']}, 10:20:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['tsvgx', 10:20:27 INFO - 'tsvgr_opacity', 10:20:27 INFO - 'tart', 10:20:27 INFO - 'tscrollx', 10:20:27 INFO - 'cart', 10:20:27 INFO - 'tsvg_static']}, 10:20:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:20:27 INFO - 'tsvgr_opacity', 10:20:27 INFO - 'tart', 10:20:27 INFO - 'tscrollx', 10:20:27 INFO - 'cart', 10:20:27 INFO - 'tsvg_static']}, 10:20:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'talos_options': ['--disable-e10s'], 10:20:27 INFO - 'tests': ['tp5o']}, 10:20:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:20:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'talos_options': ['--disable-e10s', 10:20:27 INFO - '--xperf_path', 10:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:27 INFO - 'tests': ['tp5n']}, 10:20:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:20:27 INFO - 'talos_options': ['--xperf_path', 10:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:27 INFO - 'tests': ['tp5n']}}, 10:20:27 INFO - 'talos.zip': {'path': '', 10:20:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:20:27 INFO - Running post-action listener: _resource_record_post_action 10:20:27 INFO - [mozharness: 2017-04-07 17:20:27.414805Z] Finished populate-webroot step (success) 10:20:27 INFO - [mozharness: 2017-04-07 17:20:27.414922Z] Running create-virtualenv step. 10:20:27 INFO - Running pre-action listener: _resource_record_pre_action 10:20:27 INFO - Running main action method: create_virtualenv 10:20:27 INFO - Creating virtualenv /builds/slave/test/build/venv 10:20:27 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 10:20:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:20:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:20:27 INFO - Using real prefix '/tools/python27' 10:20:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:20:28 INFO - Installing distribute.............................................................................................................................................................................................done. 10:20:31 INFO - Installing pip.................done. 10:20:31 INFO - Return code: 0 10:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:20:31 INFO - Reading from file tmpfile_stdout 10:20:31 INFO - Output received: 10:20:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:20:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:31 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:31 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 10:20:31 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 10:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:31 INFO - 'HOME': '/Users/cltbld', 10:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:31 INFO - 'LOGNAME': 'cltbld', 10:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:31 INFO - 'MOZ_NO_REMOTE': '1', 10:20:31 INFO - 'NO_EM_RESTART': '1', 10:20:31 INFO - 'PAGER': '/bin/cat', 10:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:31 INFO - 'PWD': '/builds/slave/test', 10:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:31 INFO - 'SHELL': '/bin/bash', 10:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:31 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:31 INFO - 'USER': 'cltbld', 10:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:31 INFO - 'XPC_FLAGS': '0x0', 10:20:31 INFO - 'XPC_SERVICE_NAME': '0', 10:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:31 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 10:20:31 INFO - Cleaning up... 10:20:31 INFO - Return code: 0 10:20:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:31 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:31 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 10:20:31 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 10:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:31 INFO - 'HOME': '/Users/cltbld', 10:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:31 INFO - 'LOGNAME': 'cltbld', 10:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:31 INFO - 'MOZ_NO_REMOTE': '1', 10:20:31 INFO - 'NO_EM_RESTART': '1', 10:20:31 INFO - 'PAGER': '/bin/cat', 10:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:31 INFO - 'PWD': '/builds/slave/test', 10:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:31 INFO - 'SHELL': '/bin/bash', 10:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:31 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:31 INFO - 'USER': 'cltbld', 10:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:31 INFO - 'XPC_FLAGS': '0x0', 10:20:31 INFO - 'XPC_SERVICE_NAME': '0', 10:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:31 INFO - Downloading/unpacking psutil>=3.1.1 10:20:31 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 10:20:31 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 10:20:31 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 10:20:31 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 10:20:31 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 10:20:31 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 10:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:20:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:35 INFO - Installing collected packages: psutil 10:20:35 INFO - Running setup.py install for psutil 10:20:35 INFO - building 'psutil._psutil_osx' extension 10:20:35 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 10:20:35 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 10:20:36 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 10:20:36 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 10:20:36 INFO - building 'psutil._psutil_posix' extension 10:20:36 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 10:20:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:20:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:20:36 INFO - ^ 10:20:36 INFO - 1 warning generated. 10:20:36 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 10:20:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:36 INFO - Successfully installed psutil 10:20:36 INFO - Cleaning up... 10:20:36 INFO - Return code: 0 10:20:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:20:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:36 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:36 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 10:20:36 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 10:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:36 INFO - 'HOME': '/Users/cltbld', 10:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:36 INFO - 'LOGNAME': 'cltbld', 10:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:36 INFO - 'MOZ_NO_REMOTE': '1', 10:20:36 INFO - 'NO_EM_RESTART': '1', 10:20:36 INFO - 'PAGER': '/bin/cat', 10:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:36 INFO - 'PWD': '/builds/slave/test', 10:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:36 INFO - 'SHELL': '/bin/bash', 10:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:36 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:36 INFO - 'USER': 'cltbld', 10:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:36 INFO - 'XPC_FLAGS': '0x0', 10:20:36 INFO - 'XPC_SERVICE_NAME': '0', 10:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:20:39 INFO - Installing collected packages: mozsystemmonitor 10:20:39 INFO - Running setup.py install for mozsystemmonitor 10:20:39 INFO - Successfully installed mozsystemmonitor 10:20:39 INFO - Cleaning up... 10:20:39 INFO - Return code: 0 10:20:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:39 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 10:20:39 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 10:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:39 INFO - 'HOME': '/Users/cltbld', 10:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:39 INFO - 'LOGNAME': 'cltbld', 10:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:39 INFO - 'MOZ_NO_REMOTE': '1', 10:20:39 INFO - 'NO_EM_RESTART': '1', 10:20:39 INFO - 'PAGER': '/bin/cat', 10:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:39 INFO - 'PWD': '/builds/slave/test', 10:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:39 INFO - 'SHELL': '/bin/bash', 10:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:39 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:39 INFO - 'USER': 'cltbld', 10:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:39 INFO - 'XPC_FLAGS': '0x0', 10:20:39 INFO - 'XPC_SERVICE_NAME': '0', 10:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:39 INFO - Downloading/unpacking jsonschema==2.5.1 10:20:39 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 10:20:39 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 10:20:39 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 10:20:39 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 10:20:39 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 10:20:39 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 10:20:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:20:42 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 10:20:42 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 10:20:42 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 10:20:42 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 10:20:42 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 10:20:42 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 10:20:43 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:43 INFO - warning: no files found matching '*.txt' 10:20:43 INFO - no previously-included directories found matching 'build' 10:20:43 INFO - no previously-included directories found matching 'dist' 10:20:43 INFO - no previously-included directories found matching '.git*' 10:20:43 INFO - Installing collected packages: jsonschema, functools32 10:20:43 INFO - Running setup.py install for functools32 10:20:43 INFO - warning: no files found matching '*.txt' 10:20:43 INFO - no previously-included directories found matching 'build' 10:20:43 INFO - no previously-included directories found matching 'dist' 10:20:43 INFO - no previously-included directories found matching '.git*' 10:20:43 INFO - Successfully installed jsonschema functools32 10:20:43 INFO - Cleaning up... 10:20:43 INFO - Return code: 0 10:20:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:20:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:44 INFO - 'HOME': '/Users/cltbld', 10:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:44 INFO - 'LOGNAME': 'cltbld', 10:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:44 INFO - 'MOZ_NO_REMOTE': '1', 10:20:44 INFO - 'NO_EM_RESTART': '1', 10:20:44 INFO - 'PAGER': '/bin/cat', 10:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:44 INFO - 'PWD': '/builds/slave/test', 10:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:44 INFO - 'SHELL': '/bin/bash', 10:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:44 INFO - 'USER': 'cltbld', 10:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:44 INFO - 'XPC_FLAGS': '0x0', 10:20:44 INFO - 'XPC_SERVICE_NAME': '0', 10:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:20:44 INFO - Cleaning up... 10:20:44 INFO - Return code: 0 10:20:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:44 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 10:20:44 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 10:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:44 INFO - 'HOME': '/Users/cltbld', 10:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:44 INFO - 'LOGNAME': 'cltbld', 10:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:44 INFO - 'MOZ_NO_REMOTE': '1', 10:20:44 INFO - 'NO_EM_RESTART': '1', 10:20:44 INFO - 'PAGER': '/bin/cat', 10:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:44 INFO - 'PWD': '/builds/slave/test', 10:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:44 INFO - 'SHELL': '/bin/bash', 10:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:44 INFO - 'USER': 'cltbld', 10:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:44 INFO - 'XPC_FLAGS': '0x0', 10:20:44 INFO - 'XPC_SERVICE_NAME': '0', 10:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:44 INFO - Downloading/unpacking blobuploader==1.2.4 10:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:47 INFO - Downloading blobuploader-1.2.4.tar.gz 10:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:20:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:20:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:20:48 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 10:20:48 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 10:20:48 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 10:20:48 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 10:20:48 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 10:20:48 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 10:20:48 INFO - Downloading docopt-0.6.1.tar.gz 10:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:20:48 INFO - Installing collected packages: blobuploader, requests, docopt 10:20:48 INFO - Running setup.py install for blobuploader 10:20:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:20:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:20:48 INFO - Running setup.py install for requests 10:20:49 INFO - Running setup.py install for docopt 10:20:49 INFO - Successfully installed blobuploader requests docopt 10:20:49 INFO - Cleaning up... 10:20:49 INFO - Return code: 0 10:20:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:49 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 10:20:49 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 10:20:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:49 INFO - 'HOME': '/Users/cltbld', 10:20:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:49 INFO - 'LOGNAME': 'cltbld', 10:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:49 INFO - 'MOZ_NO_REMOTE': '1', 10:20:49 INFO - 'NO_EM_RESTART': '1', 10:20:49 INFO - 'PAGER': '/bin/cat', 10:20:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:49 INFO - 'PWD': '/builds/slave/test', 10:20:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:49 INFO - 'SHELL': '/bin/bash', 10:20:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:49 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:49 INFO - 'USER': 'cltbld', 10:20:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:49 INFO - 'XPC_FLAGS': '0x0', 10:20:49 INFO - 'XPC_SERVICE_NAME': '0', 10:20:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-59q4Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-v65nWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-RJsafq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-VMxYtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-WptC5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-CEB2TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-j1fASS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZHbxWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-gVTGik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-G93kff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Snjedo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uVCw4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-6W0TB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3aXx2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-4qzjtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ca2ZkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Hs0WXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:20:51 INFO - Running setup.py install for manifestparser 10:20:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:20:51 INFO - Running setup.py install for mozcrash 10:20:51 INFO - Running setup.py install for mozdebug 10:20:52 INFO - Running setup.py install for mozdevice 10:20:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Running setup.py install for mozfile 10:20:52 INFO - Running setup.py install for mozhttpd 10:20:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Running setup.py install for mozinfo 10:20:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Running setup.py install for mozInstall 10:20:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:20:52 INFO - Running setup.py install for mozleak 10:20:52 INFO - Running setup.py install for mozlog 10:20:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Running setup.py install for moznetwork 10:20:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Running setup.py install for mozprocess 10:20:53 INFO - Running setup.py install for mozprofile 10:20:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Running setup.py install for mozrunner 10:20:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:20:53 INFO - Running setup.py install for mozscreenshot 10:20:53 INFO - Running setup.py install for moztest 10:20:54 INFO - Running setup.py install for mozversion 10:20:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:20:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:20:54 INFO - Cleaning up... 10:20:54 INFO - Return code: 0 10:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:54 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 10:20:54 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 10:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:54 INFO - 'HOME': '/Users/cltbld', 10:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:54 INFO - 'LOGNAME': 'cltbld', 10:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:54 INFO - 'MOZ_NO_REMOTE': '1', 10:20:54 INFO - 'NO_EM_RESTART': '1', 10:20:54 INFO - 'PAGER': '/bin/cat', 10:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:54 INFO - 'PWD': '/builds/slave/test', 10:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:54 INFO - 'SHELL': '/bin/bash', 10:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:54 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:54 INFO - 'USER': 'cltbld', 10:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:54 INFO - 'XPC_FLAGS': '0x0', 10:20:54 INFO - 'XPC_SERVICE_NAME': '0', 10:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-iRcMma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:54 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)) 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-9n2tQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-N5UVO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:54 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)) 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-CARLRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-qe8S_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:54 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)) 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-91ypk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-lHP7rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-acTJIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DMXOCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-JEbvef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_05wT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-o3dNdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DzJCPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:55 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-m9xex3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nNcoPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:56 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)) 10:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:56 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_mS3Uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 10:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:56 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-yihai5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:56 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)) 10:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:20:56 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)) 10:20:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:20:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:20:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:58 INFO - Downloading blessings-1.6.tar.gz 10:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:20:59 INFO - Installing collected packages: blessings 10:20:59 INFO - Running setup.py install for blessings 10:20:59 INFO - Successfully installed blessings 10:20:59 INFO - Cleaning up... 10:20:59 INFO - Return code: 0 10:20:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:59 INFO - Reading from file tmpfile_stdout 10:20:59 INFO - Current package versions: 10:20:59 INFO - blessings == 1.6 10:20:59 INFO - blobuploader == 1.2.4 10:20:59 INFO - docopt == 0.6.1 10:20:59 INFO - functools32 == 3.2.3-2 10:20:59 INFO - jsonschema == 2.5.1 10:20:59 INFO - manifestparser == 1.1 10:20:59 INFO - mozInstall == 1.12 10:20:59 INFO - mozcrash == 1.0 10:20:59 INFO - mozdebug == 0.1 10:20:59 INFO - mozdevice == 0.50 10:20:59 INFO - mozfile == 1.2 10:20:59 INFO - mozhttpd == 0.7 10:20:59 INFO - mozinfo == 0.9 10:20:59 INFO - mozleak == 0.1 10:20:59 INFO - mozlog == 3.4 10:20:59 INFO - moznetwork == 0.27 10:20:59 INFO - mozprocess == 0.25 10:20:59 INFO - mozprofile == 0.28 10:20:59 INFO - mozrunner == 6.13 10:20:59 INFO - mozscreenshot == 0.1 10:20:59 INFO - mozsystemmonitor == 0.3 10:20:59 INFO - moztest == 0.8 10:20:59 INFO - mozversion == 1.4 10:20:59 INFO - psutil == 3.1.1 10:20:59 INFO - requests == 1.2.3 10:20:59 INFO - wsgiref == 0.1.2 10:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:59 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:59 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 10:20:59 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 10:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:59 INFO - 'HOME': '/Users/cltbld', 10:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:59 INFO - 'LOGNAME': 'cltbld', 10:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:59 INFO - 'MOZ_NO_REMOTE': '1', 10:20:59 INFO - 'NO_EM_RESTART': '1', 10:20:59 INFO - 'PAGER': '/bin/cat', 10:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:59 INFO - 'PWD': '/builds/slave/test', 10:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:59 INFO - 'SHELL': '/bin/bash', 10:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:59 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:59 INFO - 'USER': 'cltbld', 10:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:59 INFO - 'XPC_FLAGS': '0x0', 10:20:59 INFO - 'XPC_SERVICE_NAME': '0', 10:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 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)) 10:20:59 INFO - Cleaning up... 10:20:59 INFO - Return code: 0 10:20:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:20:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:59 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 0x10dcc0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcc3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13487740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, '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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 10:20:59 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 10:20:59 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 10:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:59 INFO - 'HOME': '/Users/cltbld', 10:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:59 INFO - 'LOGNAME': 'cltbld', 10:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:59 INFO - 'MOZ_NO_REMOTE': '1', 10:20:59 INFO - 'NO_EM_RESTART': '1', 10:20:59 INFO - 'PAGER': '/bin/cat', 10:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:59 INFO - 'PWD': '/builds/slave/test', 10:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:59 INFO - 'SHELL': '/bin/bash', 10:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:20:59 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:59 INFO - 'USER': 'cltbld', 10:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:59 INFO - 'XPC_FLAGS': '0x0', 10:20:59 INFO - 'XPC_SERVICE_NAME': '0', 10:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:21:00 INFO - Cleaning up... 10:21:00 INFO - Return code: 0 10:21:00 INFO - Running post-action listener: _resource_record_post_action 10:21:00 INFO - Running post-action listener: _start_resource_monitoring 10:21:00 INFO - Starting resource monitoring. 10:21:00 INFO - [mozharness: 2017-04-07 17:21:00.097777Z] Finished create-virtualenv step (success) 10:21:00 INFO - [mozharness: 2017-04-07 17:21:00.098771Z] Running install step. 10:21:00 INFO - Running pre-action listener: _resource_record_pre_action 10:21:00 INFO - Running main action method: install 10:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:00 INFO - Reading from file tmpfile_stdout 10:21:00 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:00 INFO - Reading from file tmpfile_stdout 10:21:00 INFO - Output received: 10:21:00 INFO - Usage: mozinstall [options] installer 10:21:00 INFO - Options: 10:21:00 INFO - -h, --help show this help message and exit 10:21:00 INFO - -d DEST, --destination=DEST 10:21:00 INFO - Directory to install application into. [default: 10:21:00 INFO - "/builds/slave/test"] 10:21:00 INFO - --app=APP Application being installed. [default: firefox] 10:21:00 INFO - mkdir: /builds/slave/test/build/application 10:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:21:20 INFO - Reading from file tmpfile_stdout 10:21:20 INFO - Output received: 10:21:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:21:20 INFO - Running post-action listener: _resource_record_post_action 10:21:20 INFO - [mozharness: 2017-04-07 17:21:20.302609Z] Finished install step (success) 10:21:20 INFO - [mozharness: 2017-04-07 17:21:20.302751Z] Running run-tests step. 10:21:20 INFO - Running pre-action listener: _resource_record_pre_action 10:21:20 INFO - Running main action method: run_tests 10:21:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:21:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:21:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:21:20 INFO - 2017-04-07 10:21:20.317 screenresolution[2423:22550] starting screenresolution argv=screenresolution get 10:21:20 INFO - 2017-04-07 10:21:20.320 screenresolution[2423:22550] Display 0: 1600x1200x32@60 10:21:20 INFO - 2017-04-07 10:21:20.325 screenresolution[2424:22553] starting screenresolution argv=screenresolution list 10:21:20 INFO - Available Modes on Display 0 10:21:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:21:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:21:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:21:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:21:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:21:20 INFO - Intel Iris: 10:21:20 INFO - Chipset Model: Intel Iris 10:21:20 INFO - Type: GPU 10:21:20 INFO - Bus: Built-In 10:21:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:21:20 INFO - Vendor: Intel (0x8086) 10:21:20 INFO - Device ID: 0x0a2e 10:21:20 INFO - Revision ID: 0x0009 10:21:20 INFO - Displays: 10:21:20 INFO - Display: 10:21:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:21:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:21:20 INFO - Display Serial Number: 1600x1200 60 10:21:20 INFO - Main Display: Yes 10:21:20 INFO - Mirror: Off 10:21:20 INFO - Online: Yes 10:21:20 INFO - Rotation: Supported 10:21:20 INFO - Return code: 0 10:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:21:20 INFO - Python 2.7.3 10:21:20 INFO - Return code: 0 10:21:20 INFO - grabbing minidump binary from tooltool 10:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:20 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 0x10da9ed30>, '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 0x10d9c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da93be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:21:20 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 10:21:20 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 10:21:20 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 10:21:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:21:20 INFO - Return code: 0 10:21:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:21:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:21:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:21:20 INFO - ENV: RUST_BACKTRACE is now 1 10:21:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:21:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:21:20 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 10:21:20 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 10:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kJ71lrdPtJ/Render', 10:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:20 INFO - 'HOME': '/Users/cltbld', 10:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:20 INFO - 'LOGNAME': 'cltbld', 10:21:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:21:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:20 INFO - 'MOZ_NO_REMOTE': '1', 10:21:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:21:20 INFO - 'NO_EM_RESTART': '1', 10:21:20 INFO - 'PAGER': '/bin/cat', 10:21:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:20 INFO - 'PWD': '/builds/slave/test', 10:21:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:20 INFO - 'RUST_BACKTRACE': '1', 10:21:20 INFO - 'SHELL': '/bin/bash', 10:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L1Hu7RMH6h/Listeners', 10:21:20 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 10:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:20 INFO - 'USER': 'cltbld', 10:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:20 INFO - 'XPC_FLAGS': '0x0', 10:21:20 INFO - 'XPC_SERVICE_NAME': '0', 10:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:20 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 10:21:20 INFO - mozversion application_buildid: 20170407085322 10:21:20 INFO - mozversion application_changeset: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de 10:21:20 INFO - mozversion application_display_name: Nightly 10:21:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:20 INFO - mozversion application_name: Firefox 10:21:20 INFO - mozversion application_remotingname: firefox 10:21:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:21:20 INFO - mozversion application_vendor: Mozilla 10:21:20 INFO - mozversion application_version: 55.0a1 10:21:20 INFO - mozversion platform_buildid: 20170407085322 10:21:20 INFO - mozversion platform_changeset: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de 10:21:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:21:20 INFO - mozversion platform_version: 55.0a1 10:21:20 INFO - using testdate: 1491585680 10:21:20 INFO - actual date: 1491585680 10:21:20 INFO - starting webserver on 'localhost:53052' 10:21:20 INFO - SUITE-START | Running 2 tests 10:21:20 INFO - TEST-START | dromaeo_css 10:21:20 INFO - operating with platform_type : mac_ 10:21:20 INFO - Initialising browser for dromaeo_css test... 10:21:20 INFO - TEST-INFO | started process 2434 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp2Q_d7s/profile http://localhost:53052/getInfo.html) 10:21:21 INFO - PID 2434 | 1491585681540 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 10:21:21 INFO - PID 2434 | 1491585681541 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 10:21:21 INFO - PID 2434 | 1491585681593 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:21:23 INFO - PID 2434 | __metrics Screen width/height:1600/1200 10:21:23 INFO - PID 2434 | colorDepth:24 10:21:23 INFO - PID 2434 | Browser inner width/height: 1024/689 10:21:23 INFO - PID 2434 | __metrics 10:21:23 INFO - PID 2434 | 1491585683571 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:21:23 INFO - PID 2434 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:21:23 INFO - PID 2434 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:21:24 INFO - TEST-INFO | 2434: exit 0 10:21:24 INFO - Browser initialized. 10:21:24 INFO - Running cycle 1/1 for dromaeo_css test... 10:21:24 INFO - TEST-INFO | started process 2435 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp2Q_d7s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:25 INFO - PID 2435 | 1491585685016 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 10:21:25 INFO - PID 2435 | 1491585685017 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:21:36 INFO - PID 2435 | RSS: Main: 237195264 10:21:36 INFO - PID 2435 | 10:24:42 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53052/tests/dromaeo/cssquery-ext.html) 10:24:44 INFO - PID 2435 | RSS: Main: 381001728 10:24:44 INFO - PID 2435 | 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | A coding exception was thrown and uncaught in a Task. 10:26:26 INFO - PID 2435 | 10:26:26 ERROR - PID 2435 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:26:26 INFO - PID 2435 | Full stack: 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | A coding exception was thrown and uncaught in a Task. 10:26:26 INFO - PID 2435 | 10:26:26 ERROR - PID 2435 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:26:26 INFO - PID 2435 | Full stack: 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | A coding exception was thrown and uncaught in a Task. 10:26:26 INFO - PID 2435 | 10:26:26 ERROR - PID 2435 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:26:26 INFO - PID 2435 | Full stack: 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | ************************* 10:26:26 INFO - PID 2435 | A coding exception was thrown and uncaught in a Task. 10:26:26 INFO - PID 2435 | 10:26:26 ERROR - PID 2435 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:26:26 INFO - PID 2435 | Full stack: 10:26:26 INFO - PID 2435 | ************************* 10:27:46 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-ext.html (next: http://localhost:53052/tests/dromaeo/cssquery-jquery.html) 10:27:47 INFO - PID 2435 | RSS: Main: 368087040 10:27:47 INFO - PID 2435 | 10:30:49 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53052/tests/dromaeo/cssquery-mootools.html) 10:30:50 INFO - PID 2435 | RSS: Main: 333201408 10:30:50 INFO - PID 2435 | 10:33:52 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53052/tests/dromaeo/cssquery-prototype.html) 10:33:53 INFO - PID 2435 | RSS: Main: 336936960 10:33:53 INFO - PID 2435 | 10:36:57 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53052/tests/dromaeo/cssquery-yui.html) 10:36:57 INFO - PID 2435 | RSS: Main: 329338880 10:36:57 INFO - PID 2435 | 10:40:00 INFO - PID 2435 | Cycle 1(1): loaded http://localhost:53052/tests/dromaeo/cssquery-yui.html (next: http://localhost:53052/tests/dromaeo/cssquery-dojo.html) 10:40:01 INFO - PID 2435 | RSS: Main: 395517952 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | __start_tp_report 10:40:01 INFO - PID 2435 | _x_x_mozilla_page_load 10:40:01 INFO - PID 2435 | _x_x_mozilla_page_load_details 10:40:01 INFO - PID 2435 | |i|pagename|runs| 10:40:01 INFO - PID 2435 | |0;dojo.html;3456;3469.9132521686956;3491.4064609016464;3505.1237190702323;3534.3814832404323;6043.969780151104;6107.34053441714;6207.720652570634;6222.222222222223;6237.906431403527;6111.227117700293;6152.200213972177;6251.3123556408755;6268.561200715941;6271.122042913996;5216.191490319006;5375.139977603584;5389.541888939445;5407.3781515125875;5454.53636440903;5461.808836690717;5470.234167216593;5481.232627432163;5492.093804522249;5563.860903477425;5308.92036619451;5372.140457526777;5376;5387.73061346935;5441.075017247077;5372.382176049765;5383.2194331821665;5471.50756431923;5480.588955828329;5499.807506737245;5349.170878513836;5371.838844834661;5377.193420986844;5385;5432.375276843135;4489.977550112261;6159.260888693386;6178.969105154445;6193.411625895533;6197.194364732556;6348.682565871734;6420.711068001951;6440.677966101723;6540.672966351711;6542.4766018718865;6621.073049772987;6629.337066293332;6712.597244165317;6720.227173875018;6723.731050757964;6243.406876346739;6306;6313.305536390993;6324.747010119636;6360.7137678804565;6583.670816459158;6891.724331026803;6911.827204319831;6922.411595014481;6932.549384290005;6380.840478988062;6397.232332120176;6446.258680251831;6447.9355206447335;6452.806415807533;5499.230107784913;6594.934050659528;6657.434118100015;6662.966685166543;6695.4308883745425;6468;6490.318516555788;6527.967360163168;6555.4427873631275;6558.311377305409;6414.069959855888;6454.806355809239;6459.903101453482;6534;6593.076969224217;5295.602829787781;5303.4696530346655;5303.575713942875;5331.013762453958;5354.518093371537;4675.322078298627;4693.7653117344;4698.671093023524;4710.952890471051;4722.6221902247735;6171.197744293303;6274.937250627435;6288.874222515524;6321.873562528724;6385.648789316632;5563.248961390242;6689.531732778659;6733.562318449285;6737.8652426951185;6741.089952856301;6402.199725034371;6404.295527491972;6406.775762848278;6411.5832470889245;6418.19772528434;5718.685472299064;5834.562407819515;5886.411358864079;5904.321003084614;5906.734196961147;6638.269790323157;6660.83347916306;6661.267260601427;6669.999500075028;6679.866402672016;6503.934960650522;6524;6589.27517973013;6687.933120668731;6710.563813352116;6822.931770682429;6863.176418829573;6865.141857267841;6867;6892.551984121016;6565.244996825235;6570.868582628416;6575.934240657532;6578.506612004215;6637.966810165918;5829.59192856513;6453.16108905858;6524.804255872332;6534.4772418205475;6681.33186681328;6505.642189679613;6548.967255163694;6550.017497375241;6551.4103730664465;6551.672416379257;6528.21661400616;6530.183727034121;6538.934610653832;6541.280459149585;6550.181227346582;6288.402601752826;6304.117423560797;6362.491000720024;6427.775027874002;6428.935710643021;6402.807915762534;6477.093206951125;6490.643014634241;6496.155499784998;6509.251436084722;6317.431431171217;6381.361863813581;6382.776602818879;6391.680415979089;6394.200724909387 10:40:01 INFO - PID 2435 | |1;ext.html;775.0660454152745;1130.3839407522898;1153.5789436855546;1161.5411912294649;1305.9934700326496;36249;36663.81668091659;36833.894983150516;36875.81562092192;36927.26145477089;36809.26381472369;37094.25811483776;37151;37183.81408092956;37261;6111.174991376151;7876.527408355489;8005.439619226657;8022.919770802276;8029.2773650371455;6160.19917410736;8392.622331995075;8607.61265743043;8612.827743445127;8622.180892815173;7426.740064097758;8561.272291855168;8634.481931084156;8691.60887760049;8696;6131.080337949299;8607.526586037766;8651.091635378258;8665.783355416164;8679.262262707645;6126.938730612681;8230.465019773696;8401;8417.326613870875;8425.031121420992;37040.25919481622;37131.071723206864;37157.442638360444;37173;37200;3323.5845519310087;3389.034125274285;3391.5421418108485;3391.6269210386836;3393.1347506385714;29069.41861162786;29346.119616411543;29437.116886493226;29467.557986630218;29549.852250738822;2628.2115365390305;3085.2441151917924;3097.4269760094453;3108.8290144042066;3129.8904538341276;268815.999999998;272391;272951;273141;273163;38728.80635596804;39087;48080.759596201795;48205.5179448201;48351.75824120857;41264.38103428451;48849.75575122102;48891;49360;49526.257106143436;13933.303334833217;14043.297835108204;14069.71860562783;14071.366788494543;14071.71856562863;11498.482568284448;11500.712482188012;11509.827352589551;11510.021648159833;11560.768784624428;9466.668666596635;11859.58491452795;11862.347570883685;11957.282563046245;11990.04079673624;5487.533559647474;5550.833474995757;5556.861078473071;5567.415421380697;5573.191887176336;927.7495076329353;942.9768700959536;982.759223990127;999.695092996643;1010.8079464901646;22882.54283585796;24680;27874.5818812718;28272.010479633525;29005.274868128465;1057.1595581512715;1059.3590877519143;1097.7365432296192;1100.1144079016308;1113.204059097749;11710.88289117115;11938.1046421517;12007.699807504881;12020.09849261309;12034.63896083119;359509;361952;362594;362975;365074;48999.775005625146;49042.75478622584;49107.50892491101;49137.754311228215;49266.26100608494;1458.2417143085747;1460.6713489464785;1503.1281856523408;1511.1310996177242;1527.0532269992455;22500.987455564213;22549.09803607903;22562.32313030612;22648.433789154744;22764.65853012173;36452.27095458023;36493;36529.81735091307;36532.45201321929;36557.817210913774;316617.83382165886;317165;317688.4115579407;318605;318826;24674;26908.461830762884;30229.546556802106;30906.536401953537;31009.844950775103;38034.809825950695;38081;39143.41284880672;39234.60765392309;39341.40987885241;22528;27260.31849203786;27895.860520697268;28029.73866176068;28317.85841070864;25248.621270680585;26900;27555.86222068957;27698.169054928385;28086.85956570204;32065.358692826478;32776.83611581927;32808.50787238146;32820;32924.50613240851;27279.45441091207;27437.862810686616;27944.16167514978;28269.01058462944;28741.85629071925;18289.17698703588;22523.774762252167;22894.427639308484;22898.885505572365;22915.541689166457 10:40:01 INFO - PID 2435 | |2;jquery.html;424.9743592260166;428.6339406476253;437.9912401751964;438.90563528841295;456.79672545717125;20526.897365513152;21975.340739777792;22436.66345004822;22805.42986425338;22811.543769124608;22373.44066398341;22397;22433.55132897341;23574.5285094298;23826.5234695306;6049.667268300231;6176.135341052256;6263.968680156593;6272.247330320368;6274.654893980829;5962.522998160137;6239.532035097363;6240.781572644958;6246.219222597176;6250.624962502242;6204.78283260086;6222.191115155046;6230.781922632709;6732.899006514882;7075.115610548682;6679.265280819106;6916.2737912519215;6990.720371185146;6997.1603407591165;7002.124734408199;6432.421082102634;6972.895406568906;6975.093237879043;6980.7905762827195;6988.161420629506;28954.420911581652;29772.851135744182;30575.541366879515;30585.541216881764;30931.381372372427;1965.3514340267677;2209.7127373441513;2341.519988402373;2351.106579499779;2353;18825.435236942914;18830.71753923692;19261.940593267365;19455.02724863751;19613.80386196134;2223.310773660154;2254.6392577187735;2320.5242925200464;2323.546908352872;2329.860208387502;148362;154505;157570;161274;166108.16945915192;20834.47913802167;22092;22141.77858221397;23619.881900590386;23971.640425393634;41785.77677790276;41862.79068604698;41911;41934.790326048176;42038.52865149705;8791.780205494913;8870;9040.141186587263;9168.404053736485;9242.353035287466;8652.783680407914;8664.046954834963;8665.783355416039;8841.248493878093;8853.29173666105;6481.416672499403;6496.675166241669;6913.5506192097355;7647.808804779925;8160.327132937734;1846.510674671213;2195.1548653768414;2205.7132572765436;2208.7349518057936;2358.4221865643026;903.3089686389917;936.7517607833929;940.8776859008285;947.613619467719;972.2610815780058;19554.9145883581;20628.381148565568;20643.8967805163;20693.17227310921;21040.684389734168;956.1681337236648;960.9039096090474;980.9019098090276;997.3317877022413;1002.5288114586133;10544.947275263574;10921.235513513977;11040.944795275973;11045.613403530839;11058.612948546763;627849;656481;673789;696461;703058;112127.9542420593;124780;130505;133096;144097.27951360177;1018.3533456255332;1032.2670903658486;1190.5356910804794;1192.2548407245472;1193.2184419205435;21235.150593976687;21257.255996040065;21265.149394024684;21339.573208536054;21655.566888661822;60605.69697151486;61464;62039.6898015507;62384.44038899064;62902.74194515993;76209.61895190488;79017;85758.14241857502;92659;93693.59459607975;8687.478751274944;8864.246539043996;8954.373193876367;8966.506842123747;9002.774930626525;11975.28148311104;13802.792958105436;13824.516141934984;14124.576262712135;14186.219757913414;11704.414779261173;11957.282563046245;11987.64037078889;12191.51233950632;12208;10682.344236643332;11012.119030477383;11026.283291586022;11103.500342484445;11452.94273528627;10408.219383546113;11355.829662555234;11785.646430607096;12174.39128043612;12254.387280635754;8559;8620.568971551524;8924.921041506108;8930.330225233003;9065.63737450521;11237.494312755782;11680.007199388147;11688.363629092104;11760.88239117598;11789.115816313639 10:40:01 INFO - PID 2435 | |3;mootools.html;1375.6423329934216;1450.9056911300765;1470.536780914012;1471.161437980351;1471.2570152073201;10903.945480272598;11353.375564343958;11367.772644547103;11373.772524549504;11424.771504569913;10979.560817567308;11051.447427628607;11309.49107290172;11324.03745681618;11342.60300889467;2480.665110210121;2529.2285852814975;2536.201096654557;2536.720960694322;2536.936576585582;2255.052877791323;2302.458922153293;2514.572522671141;2516.8364056336372;2518.8236823422367;2409.9277021689377;2510.8242423030397;2511.0458025950115;2515.5723527000364;2516.5973444248925;2495.887685054177;2520.2565243253102;2521.470491196851;2543.097200493835;2550.7831834294016;2480.5410998965067;2495.837770544927;2501.562226610336;2522.8864701088496;2524.596064629669;11478.540858365655;11565.884341156565;11583.594574189947;11593.594224202197;11598.652040438801;6415.230172379344;6638.900416493757;6654.035164901111;6655.567388119805;6668.499862510331;3897.57126716061;4167.83328666853;4197.895052623679;4258.616724494779;4265.97867010663;1152.8270759386075;1156.2888823373648;1197.7065618923332;1272.9427175777112;1276.4064709909844;14567;15058.774118388343;15059.698806023927;15953.361865525365;16069;5263.552598029134;5383.67697938125;5554.2779438672715;5569.832905012856;5577.525910297589;10933.945330273456;11007.779844403067;11968.222065565711;11993.220440671328;11999;6483.7730679425995;6483.935160648428;6489.740410383626;6493.577917435352;6498.057781621638;5843.123531470228;6013.639181649115;6017.157597936292;6019.367966363557;6117.898778219963;4436.955630443719;4476.4180656514445;4592.081583683277;4624.491305956342;4636.86089417318;1220.4995951659778;1400.4468235047302;1437.6190309567974;1444.5377479206552;1468.59613606257;750.8273097187678;755.3088923634922;777.9222077792175;783.8275579372529;796.8325409717975;4859.416869975626;6288.811335659938;6369.904451433232;6505.642189679613;6601.405873471411;583.8365257727843;583.9270091238595;586.1266712598198;598.87423641035;602.4698265526309;3668.560407329096;4143.958560414358;4228.450301460852;4468.597826195658;4492.752898590609;13827.654308642364;14402.783958240634;14662.120272783666;14667.92666036663;14698.706025879423;14115.85884141166;14277.42890284377;14550.636234094232;14566.85433145655;14593.270336483134;10055.497225138713;10100.242481813893;10450.68647940563;10509.159267258601;10627.574897004033;5310.9468905312;6467.159269294962;6697.799066028027;6701.396874281338;6813.386795188457;11022.228444008842;11355.318680878843;12058.698532536757;12346.074044446883;12361.567345142877;12250;12289;12462.688432789253;12467.87532124667;12602.369881505705;8849;9067.818643627223;9215.216706580015;9215.539223038955;9218.354715169873;10041.347312424668;10098.444585547866;10213.08082272599;10235.385876847413;10358.274920755775;8670.566471676517;8711.346649001223;8720.12798720123;8764.956175219084;8817.382783205114;8377.664893404195;8435.072142064246;8539.316854651737;8566.700165494427;8569.614367353608;7702.152763195932;7744;7797.727079552188;7862.488938219226;7880.3301719354495;5816.534677225727;5819.418058194146;5846.590738648254;5847.7368518417625;5975.282966044103;5915.911261330997;6022.156898034191;6033.064874944391;6033.577649564489;6098.024316109401 10:40:01 INFO - PID 2435 | |4;prototype.html;392.3819983525947;398.2214770124407;399.6972590067919;409.9672026237901;410.6365866208405;5413.269208656835;5415.756290966906;5944.435278648527;6042.8489287767825;6074.179985701929;5746.281714785652;5772.307323121231;5796.855078623036;5824.359320474744;5842.912356314659;3746.763568022546;4476.48520420151;4492.775361231926;4512.578537760612;4555;4287.871363859073;4513.826405134672;4548.613367863735;4551.431071116111;4557.954420455786;3762.492063571424;4263.463239372569;4493.9775301123445;4524.276115821469;4559.062398480649;4329.610335069859;4480.955190448087;4492.415985921809;4495.213337665912;4525.50219475859;3414.3000684859544;4022.0749227677506;4026.4564283821896;4028.315186418316;4088.4276201331836;4213.6629069674655;4808.975955120237;5583.413741557119;5692.260006199209;5885.293764748258;4679;5374.3013408256675;5396.64921780083;5396.92061587684;5401.621886467949;5158.767855446514;6269.673924991741;6270.937290627081;6293.244810622755;6296.905546416808;4378.489713874501;4422.911541769146;4457.1977044132045;4488.281874900001;4521.389612402317;6330.905036424458;6539.847197029825;6546.672666366662;6599.9670001650165;6605.9339406605795;4053.8783836484954;4515.864524064284;4616.938104235977;4636.582707556337;4683.484816670163;4148.730332528376;4501.909961800811;4730.668853180313;4742.312364707167;4762.452317983407;5084.872878178076;5254.7635356409055;5341.893162136736;5351.973240133852;5399.919001214985;6075.605085669417;6260.966940454774;6304.6847657617855;6309.526785491106;6320.336364681734;1205.2768338996532;1359.626102821732;1558.9844101559065;1565.8669013133783;1594.8245692973765;900.2977677411629;1081.167501024207;1134.6766171641207;1137.8236373362142;1143.279733767721;4865.391826021747;5722.37053924068;5853.560982926298;5889.352171261158;5961.986462301419;5753.568482363839;5956.031443211453;6454.612723236621;6527.184101987252;6556.27880933097;4734.834280800155;5397.109476936262;5704.942950570441;5875.559333050039;5910.7195506493445;2579.2649095007832;3067.294522259893;3240.5949256342956;3300.745716627714;3369.983150084283;6088.634681919099;6118.969405153035;6520.4131628152745;6628.96685516579;6644.8671026579195;5010.448850626428;6070.991432068561;6071.9981203101;6582.44049255809;6597.30728273534;6588.682263547214;6611.892410567026;6612.140421745143;6622.635755033327;6642.505436276799;5087.796488140581;5401.081816091195;5589.413111623221;6431.485481161402;6449.580777249464;6028.57799953999;6325.652089135142;6345.428911397997;6358;6422.486201103994;6077.544184186116;6392.488600911823;6478.093066970534;6553.590977939575;6584.374484423972;3946.861859834892;4357.172137293904;4389.363542286287;4636.235021221461;4643.373144625568;5300.443453437333;5397.27136836522;5486.351576201941;6208.81373558794;6385.521085918667;6255.499560035095;6329.493640508656;6338.334474880256;6339.746410143543;6340.100837984698;5620.662760234235;6126.661794046234;6267.4986001120715;6285.962816135287;6296.748130074746;6116.633002019893;6150.261968563801;6156.138140660221;6176.722047507963;6206.6345404011045;5938.287405511366;5972.58191926561;6287.497000240061;6292.2134733158355;6294.8111556653375;5986.700664966821;6035.732496175677;6132.264128304632;6167.907481387693;6175.135481032748 10:40:01 INFO - PID 2435 | |5;yui.html;707.7735124760077;728.3190217146968;807.4105902691032;833.7707130539097;842.2335674536173;15663.451779187732;15812.44656437025;16000.599985000381;16155.515334539969;16721.414750483735;13228.735425291488;15278.923605381957;15404.537863864101;15451.22743862808;16601.750973735405;964.7298756348199;1000.2698030437784;1002.9297949143563;1004.3772860826261;1004.9296549241556;953.7567920180344;994.9204063674889;1005.0552480668185;1008.1027885182157;1013.8681971343715;1049.8687664041995;1061.2624226162782;1062.760878802271;1063.9892102502654;1066.8506409102731;1044.3107549017689;1047.539082803564;1047.8009178256107;1049.559185142242;1059.6291298045699;775.5153029356652;777.564563844249;778.2023425988351;778.7079845058104;779.0067663728792;16510.587235319093;16948.3220671173;16977.405790797264;17034.400711807742;17320.307187712475;14709.338079786328;14724.33740481681;14789.40842366304;14829.703405931821;14852.70294594102;13274.336283185801;13386.330683465787;13432.529861454803;13524.729505409936;13630.93184534071;2918.693537178608;3325.700686938163;3356.060303115132;3368.444206705891;3371.039253812676;120461.39769301185;122915;123214.38392808069;124796.37601812024;124925.37537312346;11458.828117578243;11520.868639301589;11542.538298467967;11740.70648233801;11958.58144964922;22528.887355563118;25121;25472.599007054418;25575.104871329786;25622.743772562037;7882.330001949784;8118.35053195743;8120.269175774209;8216.014078310642;8217.383696222807;4107.9178416431505;4231.407602935591;4236.152769446123;4275.080857615565;4323.135372925428;4035.2534781065597;4097.8975525612095;4150.377443383456;4152.231837110145;4157.729747566398;720.9567425954355;843.2031730015077;849.3884403229665;851.6082602003024;855.0252711908429;548.1687086199734;562.794579978305;608.8021393047277;618.9381061893774;633.4805459523238;9425.19885809714;9803.607855685836;9906.702798916043;9958.094178864332;10068.194544436428;553.8144721518337;600.7356763024255;602.2420098584886;616.2758758458793;654.6890227142164;4409.603135717801;4544.909101817945;4563.589276965089;4608.358979450001;4660.577586164117;101507;105637;106069;107077.46461267644;107345.4632726847;94765.52617237008;95870;98181;98366;98896.50551747195;56744.26447395815;67701.66149169223;72351;73923.99999999892;78020;9942.801143976936;9999.35004224723;10623.256372054191;10734.248602598056;10758.62344817959;36477;38230.39310780469;38568;39247.41128883126;39348.213035739696;36340.45489317715;50932.472025839284;51365.486345137564;51600;52628.47371526236;12962.416691249105;13059;13130.868691313346;13425.462981480632;13841.51554695621;14072.718545629235;14080.577582672537;14429.567112986628;14559.636009099857;15126.697466050397;12778.808317875053;12965.870341296844;13131.212127272394;13149.276789776653;13512.391942362421;12320.137590368946;12510.085984565321;12510.856652257655;12529;13055.281959492318;12224.379870308383;12332.938335308267;12405.317707526172;12488.937555312164;12900.29048402347;8822.358864147434;9121.035663409286;9183.448993060438;9384.390014649027;9545.31602060348;10932.453377331261;11053.502592383524;11106.833397499206;11241.156913231704;11250.493727782106 10:40:01 INFO - PID 2435 | __end_tp_report 10:40:01 INFO - PID 2435 | __start_cc_report 10:40:01 INFO - PID 2435 | _x_x_mozilla_cycle_collect,1834 10:40:01 INFO - PID 2435 | __end_cc_report 10:40:01 INFO - PID 2435 | __startTimestamp1491586801258__endTimestamp 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | ------- Summary: start ------- 10:40:01 INFO - PID 2435 | Number of tests: 6 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#0] dojo.html Cycles:165 Average:6102.93 Median:6394.20 stddev:719.85 (11.3%) stddev-sans-first:691.46 10:40:01 INFO - PID 2435 | Values: 3456.0 3469.9 3491.4 3505.1 3534.4 6044.0 6107.3 6207.7 6222.2 6237.9 6111.2 6152.2 6251.3 6268.6 6271.1 5216.2 5375.1 5389.5 5407.4 5454.5 5461.8 5470.2 5481.2 5492.1 5563.9 5308.9 5372.1 5376.0 5387.7 5441.1 5372.4 5383.2 5471.5 5480.6 5499.8 5349.2 5371.8 5377.2 5385.0 5432.4 4490.0 6159.3 6179.0 6193.4 6197.2 6348.7 6420.7 6440.7 6540.7 6542.5 6621.1 6629.3 6712.6 6720.2 6723.7 6243.4 6306.0 6313.3 6324.7 6360.7 6583.7 6891.7 6911.8 6922.4 6932.5 6380.8 6397.2 6446.3 6447.9 6452.8 5499.2 6594.9 6657.4 6663.0 6695.4 6468.0 6490.3 6528.0 6555.4 6558.3 6414.1 6454.8 6459.9 6534.0 6593.1 5295.6 5303.5 5303.6 5331.0 5354.5 4675.3 4693.8 4698.7 4711.0 4722.6 6171.2 6274.9 6288.9 6321.9 6385.6 5563.2 6689.5 6733.6 6737.9 6741.1 6402.2 6404.3 6406.8 6411.6 6418.2 5718.7 5834.6 5886.4 5904.3 5906.7 6638.3 6660.8 6661.3 6670.0 6679.9 6503.9 6524.0 6589.3 6687.9 6710.6 6822.9 6863.2 6865.1 6867.0 6892.6 6565.2 6570.9 6575.9 6578.5 6638.0 5829.6 6453.2 6524.8 6534.5 6681.3 6505.6 6549.0 6550.0 6551.4 6551.7 6528.2 6530.2 6538.9 6541.3 6550.2 6288.4 6304.1 6362.5 6427.8 6428.9 6402.8 6477.1 6490.6 6496.2 6509.3 6317.4 6381.4 6382.8 6391.7 6394.2 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#1] ext.html Cycles:180 Average:45251.80 Median:32792.67 stddev:84227.67 (256.8%) stddev-sans-first:84397.75 10:40:01 INFO - PID 2435 | Values: 775.1 1130.4 1153.6 1161.5 1306.0 36249.0 36663.8 36833.9 36875.8 36927.3 36809.3 37094.3 37151.0 37183.8 37261.0 6111.2 7876.5 8005.4 8022.9 8029.3 6160.2 8392.6 8607.6 8612.8 8622.2 7426.7 8561.3 8634.5 8691.6 8696.0 6131.1 8607.5 8651.1 8665.8 8679.3 6126.9 8230.5 8401.0 8417.3 8425.0 37040.3 37131.1 37157.4 37173.0 37200.0 3323.6 3389.0 3391.5 3391.6 3393.1 29069.4 29346.1 29437.1 29467.6 29549.9 2628.2 3085.2 3097.4 3108.8 3129.9 268816.0 272391.0 272951.0 273141.0 273163.0 38728.8 39087.0 48080.8 48205.5 48351.8 41264.4 48849.8 48891.0 49360.0 49526.3 13933.3 14043.3 14069.7 14071.4 14071.7 11498.5 11500.7 11509.8 11510.0 11560.8 9466.7 11859.6 11862.3 11957.3 11990.0 5487.5 5550.8 5556.9 5567.4 5573.2 927.7 943.0 982.8 999.7 1010.8 22882.5 24680.0 27874.6 28272.0 29005.3 1057.2 1059.4 1097.7 1100.1 1113.2 11710.9 11938.1 12007.7 12020.1 12034.6 359509.0 361952.0 362594.0 362975.0 365074.0 48999.8 49042.8 49107.5 49137.8 49266.3 1458.2 1460.7 1503.1 1511.1 1527.1 22501.0 22549.1 22562.3 22648.4 22764.7 36452.3 36493.0 36529.8 36532.5 36557.8 316617.8 317165.0 317688.4 318605.0 318826.0 24674.0 26908.5 30229.5 30906.5 31009.8 38034.8 38081.0 39143.4 39234.6 39341.4 22528.0 27260.3 27895.9 28029.7 28317.9 25248.6 26900.0 27555.9 27698.2 28086.9 32065.4 32776.8 32808.5 32820.0 32924.5 27279.5 27437.9 27944.2 28269.0 28741.9 18289.2 22523.8 22894.4 22898.9 22915.5 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#2] jquery.html Cycles:180 Average:40619.99 Median:26463.03 stddev:112358.44 (424.6%) stddev-sans-first:112633.10 10:40:01 INFO - PID 2435 | Values: 425.0 428.6 438.0 438.9 456.8 20526.9 21975.3 22436.7 22805.4 22811.5 22373.4 22397.0 22433.6 23574.5 23826.5 6049.7 6176.1 6264.0 6272.2 6274.7 5962.5 6239.5 6240.8 6246.2 6250.6 6204.8 6222.2 6230.8 6732.9 7075.1 6679.3 6916.3 6990.7 6997.2 7002.1 6432.4 6972.9 6975.1 6980.8 6988.2 28954.4 29772.9 30575.5 30585.5 30931.4 1965.4 2209.7 2341.5 2351.1 2353.0 18825.4 18830.7 19261.9 19455.0 19613.8 2223.3 2254.6 2320.5 2323.5 2329.9 148362.0 154505.0 157570.0 161274.0 166108.2 20834.5 22092.0 22141.8 23619.9 23971.6 41785.8 41862.8 41911.0 41934.8 42038.5 8791.8 8870.0 9040.1 9168.4 9242.4 8652.8 8664.0 8665.8 8841.2 8853.3 6481.4 6496.7 6913.6 7647.8 8160.3 1846.5 2195.2 2205.7 2208.7 2358.4 903.3 936.8 940.9 947.6 972.3 19554.9 20628.4 20643.9 20693.2 21040.7 956.2 960.9 980.9 997.3 1002.5 10544.9 10921.2 11040.9 11045.6 11058.6 627849.0 656481.0 673789.0 696461.0 703058.0 112128.0 124780.0 130505.0 133096.0 144097.3 1018.4 1032.3 1190.5 1192.3 1193.2 21235.2 21257.3 21265.1 21339.6 21655.6 60605.7 61464.0 62039.7 62384.4 62902.7 76209.6 79017.0 85758.1 92659.0 93693.6 8687.5 8864.2 8954.4 8966.5 9002.8 11975.3 13802.8 13824.5 14124.6 14186.2 11704.4 11957.3 11987.6 12191.5 12208.0 10682.3 11012.1 11026.3 11103.5 11452.9 10408.2 11355.8 11785.6 12174.4 12254.4 8559.0 8620.6 8924.9 8930.3 9065.6 11237.5 11680.0 11688.4 11760.9 11789.1 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#3] mootools.html Cycles:180 Average:6903.96 Median:2536.46 stddev:4262.12 (168.0%) stddev-sans-first:4253.83 10:40:01 INFO - PID 2435 | Values: 1375.6 1450.9 1470.5 1471.2 1471.3 10903.9 11353.4 11367.8 11373.8 11424.8 10979.6 11051.4 11309.5 11324.0 11342.6 2480.7 2529.2 2536.2 2536.7 2536.9 2255.1 2302.5 2514.6 2516.8 2518.8 2409.9 2510.8 2511.0 2515.6 2516.6 2495.9 2520.3 2521.5 2543.1 2550.8 2480.5 2495.8 2501.6 2522.9 2524.6 11478.5 11565.9 11583.6 11593.6 11598.7 6415.2 6638.9 6654.0 6655.6 6668.5 3897.6 4167.8 4197.9 4258.6 4266.0 1152.8 1156.3 1197.7 1272.9 1276.4 14567.0 15058.8 15059.7 15953.4 16069.0 5263.6 5383.7 5554.3 5569.8 5577.5 10933.9 11007.8 11968.2 11993.2 11999.0 6483.8 6483.9 6489.7 6493.6 6498.1 5843.1 6013.6 6017.2 6019.4 6117.9 4437.0 4476.4 4592.1 4624.5 4636.9 1220.5 1400.4 1437.6 1444.5 1468.6 750.8 755.3 777.9 783.8 796.8 4859.4 6288.8 6369.9 6505.6 6601.4 583.8 583.9 586.1 598.9 602.5 3668.6 4144.0 4228.5 4468.6 4492.8 13827.7 14402.8 14662.1 14667.9 14698.7 14115.9 14277.4 14550.6 14566.9 14593.3 10055.5 10100.2 10450.7 10509.2 10627.6 5310.9 6467.2 6697.8 6701.4 6813.4 11022.2 11355.3 12058.7 12346.1 12361.6 12250.0 12289.0 12462.7 12467.9 12602.4 8849.0 9067.8 9215.2 9215.5 9218.4 10041.3 10098.4 10213.1 10235.4 10358.3 8670.6 8711.3 8720.1 8765.0 8817.4 8377.7 8435.1 8539.3 8566.7 8569.6 7702.2 7744.0 7797.7 7862.5 7880.3 5816.5 5819.4 5846.6 5847.7 5975.3 5915.9 6022.2 6033.1 6033.6 6098.0 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#4] prototype.html Cycles:180 Average:5092.93 Median:5656.46 stddev:1541.69 (27.3%) stddev-sans-first:1505.10 10:40:01 INFO - PID 2435 | Values: 392.4 398.2 399.7 410.0 410.6 5413.3 5415.8 5944.4 6042.8 6074.2 5746.3 5772.3 5796.9 5824.4 5842.9 3746.8 4476.5 4492.8 4512.6 4555.0 4287.9 4513.8 4548.6 4551.4 4558.0 3762.5 4263.5 4494.0 4524.3 4559.1 4329.6 4481.0 4492.4 4495.2 4525.5 3414.3 4022.1 4026.5 4028.3 4088.4 4213.7 4809.0 5583.4 5692.3 5885.3 4679.0 5374.3 5396.6 5396.9 5401.6 5158.8 6269.7 6270.9 6293.2 6296.9 4378.5 4422.9 4457.2 4488.3 4521.4 6330.9 6539.8 6546.7 6600.0 6605.9 4053.9 4515.9 4616.9 4636.6 4683.5 4148.7 4501.9 4730.7 4742.3 4762.5 5084.9 5254.8 5341.9 5352.0 5399.9 6075.6 6261.0 6304.7 6309.5 6320.3 1205.3 1359.6 1559.0 1565.9 1594.8 900.3 1081.2 1134.7 1137.8 1143.3 4865.4 5722.4 5853.6 5889.4 5962.0 5753.6 5956.0 6454.6 6527.2 6556.3 4734.8 5397.1 5704.9 5875.6 5910.7 2579.3 3067.3 3240.6 3300.7 3370.0 6088.6 6119.0 6520.4 6629.0 6644.9 5010.4 6071.0 6072.0 6582.4 6597.3 6588.7 6611.9 6612.1 6622.6 6642.5 5087.8 5401.1 5589.4 6431.5 6449.6 6028.6 6325.7 6345.4 6358.0 6422.5 6077.5 6392.5 6478.1 6553.6 6584.4 3946.9 4357.2 4389.4 4636.2 4643.4 5300.4 5397.3 5486.4 6208.8 6385.5 6255.5 6329.5 6338.3 6339.7 6340.1 5620.7 6126.7 6267.5 6286.0 6296.7 6116.6 6150.3 6156.1 6176.7 6206.6 5938.3 5972.6 6287.5 6292.2 6294.8 5986.7 6035.7 6132.3 6167.9 6175.1 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | [#5] yui.html Cycles:180 Average:20392.00 Median:19924.60 stddev:30345.27 (152.3%) stddev-sans-first:30394.40 10:40:01 INFO - PID 2435 | Values: 707.8 728.3 807.4 833.8 842.2 15663.5 15812.4 16000.6 16155.5 16721.4 13228.7 15278.9 15404.5 15451.2 16601.8 964.7 1000.3 1002.9 1004.4 1004.9 953.8 994.9 1005.1 1008.1 1013.9 1049.9 1061.3 1062.8 1064.0 1066.9 1044.3 1047.5 1047.8 1049.6 1059.6 775.5 777.6 778.2 778.7 779.0 16510.6 16948.3 16977.4 17034.4 17320.3 14709.3 14724.3 14789.4 14829.7 14852.7 13274.3 13386.3 13432.5 13524.7 13630.9 2918.7 3325.7 3356.1 3368.4 3371.0 120461.4 122915.0 123214.4 124796.4 124925.4 11458.8 11520.9 11542.5 11740.7 11958.6 22528.9 25121.0 25472.6 25575.1 25622.7 7882.3 8118.4 8120.3 8216.0 8217.4 4107.9 4231.4 4236.2 4275.1 4323.1 4035.3 4097.9 4150.4 4152.2 4157.7 721.0 843.2 849.4 851.6 855.0 548.2 562.8 608.8 618.9 633.5 9425.2 9803.6 9906.7 9958.1 10068.2 553.8 600.7 602.2 616.3 654.7 4409.6 4544.9 4563.6 4608.4 4660.6 101507.0 105637.0 106069.0 107077.5 107345.5 94765.5 95870.0 98181.0 98366.0 98896.5 56744.3 67701.7 72351.0 73924.0 78020.0 9942.8 9999.4 10623.3 10734.2 10758.6 36477.0 38230.4 38568.0 39247.4 39348.2 36340.5 50932.5 51365.5 51600.0 52628.5 12962.4 13059.0 13130.9 13425.5 13841.5 14072.7 14080.6 14429.6 14559.6 15126.7 12778.8 12965.9 13131.2 13149.3 13512.4 12320.1 12510.1 12510.9 12529.0 13055.3 12224.4 12332.9 12405.3 12488.9 12900.3 8822.4 9121.0 9183.4 9384.4 9545.3 10932.5 11053.5 11106.8 11241.2 11250.5 10:40:01 INFO - PID 2435 | -------- Summary: end -------- 10:40:01 INFO - PID 2435 | 10:40:01 INFO - PID 2435 | 1491586801285 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:40:01 INFO - TEST-INFO | 2435: exit 0 10:40:01 INFO - TEST-OK | dromaeo_css | took 1121072ms 10:40:01 INFO - TEST-START | kraken 10:40:01 INFO - operating with platform_type : mac_ 10:40:01 INFO - Initialising browser for kraken test... 10:40:02 INFO - TEST-INFO | started process 2439 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpX9ZGZW/profile http://localhost:53052/getInfo.html) 10:40:02 INFO - PID 2439 | 1491586802606 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 10:40:02 INFO - PID 2439 | 1491586802607 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 10:40:02 INFO - PID 2439 | 1491586802659 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:40:04 INFO - PID 2439 | __metrics Screen width/height:1600/1200 10:40:04 INFO - PID 2439 | colorDepth:24 10:40:04 INFO - PID 2439 | Browser inner width/height: 1024/689 10:40:04 INFO - PID 2439 | __metrics 10:40:04 INFO - PID 2439 | 1491586804769 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:40:04 INFO - PID 2439 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:40:04 INFO - PID 2439 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:40:05 INFO - TEST-INFO | 2439: exit 0 10:40:05 INFO - Browser initialized. 10:40:05 INFO - Running cycle 1/1 for kraken test... 10:40:05 INFO - TEST-INFO | started process 2440 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpX9ZGZW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:06 INFO - PID 2440 | 1491586806176 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 10:40:06 INFO - PID 2440 | 1491586806177 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:40:18 INFO - PID 2440 | RSS: Main: 237838336 10:40:18 INFO - PID 2440 | 10:40:21 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53052/tests/kraken/driver.html?testName=audio-beat-detection) 10:40:22 INFO - PID 2440 | RSS: Main: 292356096 10:40:22 INFO - PID 2440 | 10:40:24 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53052/tests/kraken/driver.html?testName=audio-dft) 10:40:25 INFO - PID 2440 | RSS: Main: 292749312 10:40:25 INFO - PID 2440 | 10:40:29 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53052/tests/kraken/driver.html?testName=audio-fft) 10:40:29 INFO - PID 2440 | RSS: Main: 390086656 10:40:29 INFO - PID 2440 | 10:40:31 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53052/tests/kraken/driver.html?testName=audio-oscillator) 10:40:32 INFO - PID 2440 | RSS: Main: 289021952 10:40:32 INFO - PID 2440 | 10:40:34 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53052/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:40:35 INFO - PID 2440 | RSS: Main: 291352576 10:40:35 INFO - PID 2440 | 10:40:38 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53052/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:39 INFO - PID 2440 | RSS: Main: 334000128 10:40:39 INFO - PID 2440 | 10:40:42 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53052/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:42 INFO - PID 2440 | RSS: Main: 342577152 10:40:42 INFO - PID 2440 | 10:40:46 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53052/tests/kraken/driver.html?testName=json-parse-financial) 10:40:46 INFO - PID 2440 | RSS: Main: 346529792 10:40:46 INFO - PID 2440 | 10:40:48 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53052/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:48 INFO - PID 2440 | RSS: Main: 311058432 10:40:48 INFO - PID 2440 | 10:40:50 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:51 INFO - PID 2440 | RSS: Main: 313434112 10:40:51 INFO - PID 2440 | 10:40:53 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:54 INFO - PID 2440 | RSS: Main: 315572224 10:40:54 INFO - PID 2440 | 10:40:57 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:58 INFO - PID 2440 | RSS: Main: 334254080 10:40:58 INFO - PID 2440 | 10:41:01 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:41:02 INFO - PID 2440 | RSS: Main: 316100608 10:41:02 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | Cycle 1(1): loaded http://localhost:53052/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53052/tests/kraken/driver.html?testName=ai-astar) 10:41:04 INFO - PID 2440 | RSS: Main: 382148608 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | __start_tp_report 10:41:04 INFO - PID 2440 | _x_x_mozilla_page_load 10:41:04 INFO - PID 2440 | _x_x_mozilla_page_load_details 10:41:04 INFO - PID 2440 | |i|pagename|runs| 10:41:04 INFO - PID 2440 | |0;ai-astar;111;124;123;125;124;123;121;121;124;122 10:41:04 INFO - PID 2440 | |1;audio-beat-detection;160;121;118;162;127;123;115;119;144;118 10:41:04 INFO - PID 2440 | |2;audio-dft;217;212;214;208;212;291;250;211;212;210 10:41:04 INFO - PID 2440 | |3;audio-fft;90;66;65;102;68;65;63;64;81;62 10:41:04 INFO - PID 2440 | |4;audio-oscillator;92;72;95;61;94;112;133;75;72;92 10:41:04 INFO - PID 2440 | |5;imaging-gaussian-blur;92;92;94;96;117;92;92;92;93;94 10:41:04 INFO - PID 2440 | |6;imaging-darkroom;100;101;100;100;100;101;100;101;100;99 10:41:04 INFO - PID 2440 | |7;imaging-desaturate;101;100;102;101;101;102;102;102;100;131 10:41:04 INFO - PID 2440 | |8;json-parse-financial;55;51;51;51;51;51;53;50;51;52 10:41:04 INFO - PID 2440 | |9;json-stringify-tinderbox;58;63;72;73;70;66;66;66;66;66 10:41:04 INFO - PID 2440 | |10;stanford-crypto-aes;90;92;90;92;90;94;110;117;116;88 10:41:04 INFO - PID 2440 | |11;stanford-crypto-ccm;168;159;161;174;172;170;151;150;193;150 10:41:04 INFO - PID 2440 | |12;stanford-crypto-pbkdf2;200;203;211;206;202;204;238;273;198;199 10:41:04 INFO - PID 2440 | |13;stanford-crypto-sha256-iterative;74;75;93;67;68;82;112;107;62;89 10:41:04 INFO - PID 2440 | __end_tp_report 10:41:04 INFO - PID 2440 | __start_cc_report 10:41:04 INFO - PID 2440 | _x_x_mozilla_cycle_collect,2370 10:41:04 INFO - PID 2440 | __end_cc_report 10:41:04 INFO - PID 2440 | __startTimestamp1491586864936__endTimestamp 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | ------- Summary: start ------- 10:41:04 INFO - PID 2440 | Number of tests: 14 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#0] ai-astar Cycles:10 Average:121.80 Median:123.50 stddev:4.02 (3.3%) stddev-sans-first:1.41 10:41:04 INFO - PID 2440 | Values: 111.0 124.0 123.0 125.0 124.0 123.0 121.0 121.0 124.0 122.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:125.00 stddev:17.91 (14.3%) stddev-sans-first:15.55 10:41:04 INFO - PID 2440 | Values: 160.0 121.0 118.0 162.0 127.0 123.0 115.0 119.0 144.0 118.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#2] audio-dft Cycles:10 Average:223.70 Median:213.00 stddev:26.60 (12.5%) stddev-sans-first:28.10 10:41:04 INFO - PID 2440 | Values: 217.0 212.0 214.0 208.0 212.0 291.0 250.0 211.0 212.0 210.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#3] audio-fft Cycles:10 Average:72.60 Median:65.50 stddev:13.73 (21.0%) stddev-sans-first:13.04 10:41:04 INFO - PID 2440 | Values: 90.0 66.0 65.0 102.0 68.0 65.0 63.0 64.0 81.0 62.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:83.50 stddev:21.33 (25.5%) stddev-sans-first:22.61 10:41:04 INFO - PID 2440 | Values: 92.0 72.0 95.0 61.0 94.0 112.0 133.0 75.0 72.0 92.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:92.50 stddev:7.71 (8.3%) stddev-sans-first:8.07 10:41:04 INFO - PID 2440 | Values: 92.0 92.0 94.0 96.0 117.0 92.0 92.0 92.0 93.0 94.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:100.50 stddev:0.63 (0.6%) stddev-sans-first:0.67 10:41:04 INFO - PID 2440 | Values: 100.0 101.0 100.0 100.0 100.0 101.0 100.0 101.0 100.0 99.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:102.00 stddev:9.45 (9.3%) stddev-sans-first:9.95 10:41:04 INFO - PID 2440 | Values: 101.0 100.0 102.0 101.0 101.0 102.0 102.0 102.0 100.0 131.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.00 stddev:1.43 (2.8%) stddev-sans-first:0.83 10:41:04 INFO - PID 2440 | Values: 55.0 51.0 51.0 51.0 51.0 51.0 53.0 50.0 51.0 52.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.00 stddev:4.35 (6.6%) stddev-sans-first:3.32 10:41:04 INFO - PID 2440 | Values: 58.0 63.0 72.0 73.0 70.0 66.0 66.0 66.0 66.0 66.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:90.00 stddev:11.59 (12.9%) stddev-sans-first:11.94 10:41:04 INFO - PID 2440 | Values: 90.0 92.0 90.0 92.0 90.0 94.0 110.0 117.0 116.0 88.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#11] stanford-crypto-ccm Cycles:10 Average:164.80 Median:169.00 stddev:13.52 (8.0%) stddev-sans-first:14.29 10:41:04 INFO - PID 2440 | Values: 168.0 159.0 161.0 174.0 172.0 170.0 151.0 150.0 193.0 150.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:213.40 Median:205.00 stddev:23.96 (11.7%) stddev-sans-first:24.92 10:41:04 INFO - PID 2440 | Values: 200.0 203.0 211.0 206.0 202.0 204.0 238.0 273.0 198.0 199.0 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.90 Median:74.50 stddev:17.06 (22.9%) stddev-sans-first:17.79 10:41:04 INFO - PID 2440 | Values: 74.0 75.0 93.0 67.0 68.0 82.0 112.0 107.0 62.0 89.0 10:41:04 INFO - PID 2440 | -------- Summary: end -------- 10:41:04 INFO - PID 2440 | 10:41:04 INFO - PID 2440 | 1491586864965 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:41:06 INFO - TEST-INFO | 2440: exit 0 10:41:06 INFO - TEST-OK | kraken | took 64264ms 10:41:06 INFO - SUITE-END | took 1185s 10:41:06 INFO - Completed test suite (00:19:45) 10:41:06 INFO - javascript benchmark 10:41:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7716.476641137913, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3456.0, 3469.9132521686956, 3491.4064609016464, 3505.1237190702323, 3534.3814832404323, 6043.969780151104, 6107.34053441714, 6207.720652570634, 6222.222222222223, 6237.906431403527, 6111.227117700293, 6152.200213972177, 6251.3123556408755, 6268.561200715941, 6271.122042913996, 5216.191490319006, 5375.139977603584, 5389.541888939445, 5407.3781515125875, 5454.53636440903, 5461.808836690717, 5470.234167216593, 5481.232627432163, 5492.093804522249, 5563.860903477425, 5308.92036619451, 5372.140457526777, 5376.0, 5387.73061346935, 5441.075017247077, 5372.382176049765, 5383.2194331821665, 5471.50756431923, 5480.588955828329, 5499.807506737245, 5349.170878513836, 5371.838844834661, 5377.193420986844, 5385.0, 5432.375276843135, 4489.977550112261, 6159.260888693386, 6178.969105154445, 6193.411625895533, 6197.194364732556, 6348.682565871734, 6420.711068001951, 6440.677966101723, 6540.672966351711, 6542.4766018718865, 6621.073049772987, 6629.337066293332, 6712.597244165317, 6720.227173875018, 6723.731050757964, 6243.406876346739, 6306.0, 6313.305536390993, 6324.747010119636, 6360.7137678804565, 6583.670816459158, 6891.724331026803, 6911.827204319831, 6922.411595014481, 6932.549384290005, 6380.840478988062, 6397.232332120176, 6446.258680251831, 6447.9355206447335, 6452.806415807533, 5499.230107784913, 6594.934050659528, 6657.434118100015, 6662.966685166543, 6695.4308883745425, 6468.0, 6490.318516555788, 6527.967360163168, 6555.4427873631275, 6558.311377305409, 6414.069959855888, 6454.806355809239, 6459.903101453482, 6534.0, 6593.076969224217, 5295.602829787781, 5303.4696530346655, 5303.575713942875, 5331.013762453958, 5354.518093371537, 4675.322078298627, 4693.7653117344, 4698.671093023524, 4710.952890471051, 4722.6221902247735, 6171.197744293303, 6274.937250627435, 6288.874222515524, 6321.873562528724, 6385.648789316632, 5563.248961390242, 6689.531732778659, 6733.562318449285, 6737.8652426951185, 6741.089952856301, 6402.199725034371, 6404.295527491972, 6406.775762848278, 6411.5832470889245, 6418.19772528434, 5718.685472299064, 5834.562407819515, 5886.411358864079, 5904.321003084614, 5906.734196961147, 6638.269790323157, 6660.83347916306, 6661.267260601427, 6669.999500075028, 6679.866402672016, 6503.934960650522, 6524.0, 6589.27517973013, 6687.933120668731, 6710.563813352116, 6822.931770682429, 6863.176418829573, 6865.141857267841, 6867.0, 6892.551984121016, 6565.244996825235, 6570.868582628416, 6575.934240657532, 6578.506612004215, 6637.966810165918, 5829.59192856513, 6453.16108905858, 6524.804255872332, 6534.4772418205475, 6681.33186681328, 6505.642189679613, 6548.967255163694, 6550.017497375241, 6551.4103730664465, 6551.672416379257, 6528.21661400616, 6530.183727034121, 6538.934610653832, 6541.280459149585, 6550.181227346582, 6288.402601752826, 6304.117423560797, 6362.491000720024, 6427.775027874002, 6428.935710643021, 6402.807915762534, 6477.093206951125, 6490.643014634241, 6496.155499784998, 6509.251436084722, 6317.431431171217, 6381.361863813581, 6382.776602818879, 6391.680415979089, 6394.200724909387], "value": 6055.317390197954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.0660454152745, 1130.3839407522898, 1153.5789436855546, 1161.5411912294649, 1305.9934700326496, 36249.0, 36663.81668091659, 36833.894983150516, 36875.81562092192, 36927.26145477089, 36809.26381472369, 37094.25811483776, 37151.0, 37183.81408092956, 37261.0, 6111.174991376151, 7876.527408355489, 8005.439619226657, 8022.919770802276, 8029.2773650371455, 6160.19917410736, 8392.622331995075, 8607.61265743043, 8612.827743445127, 8622.180892815173, 7426.740064097758, 8561.272291855168, 8634.481931084156, 8691.60887760049, 8696.0, 6131.080337949299, 8607.526586037766, 8651.091635378258, 8665.783355416164, 8679.262262707645, 6126.938730612681, 8230.465019773696, 8401.0, 8417.326613870875, 8425.031121420992, 37040.25919481622, 37131.071723206864, 37157.442638360444, 37173.0, 37200.0, 3323.5845519310087, 3389.034125274285, 3391.5421418108485, 3391.6269210386836, 3393.1347506385714, 29069.41861162786, 29346.119616411543, 29437.116886493226, 29467.557986630218, 29549.852250738822, 2628.2115365390305, 3085.2441151917924, 3097.4269760094453, 3108.8290144042066, 3129.8904538341276, 268815.999999998, 272391.0, 272951.0, 273141.0, 273163.0, 38728.80635596804, 39087.0, 48080.759596201795, 48205.5179448201, 48351.75824120857, 41264.38103428451, 48849.75575122102, 48891.0, 49360.0, 49526.257106143436, 13933.303334833217, 14043.297835108204, 14069.71860562783, 14071.366788494543, 14071.71856562863, 11498.482568284448, 11500.712482188012, 11509.827352589551, 11510.021648159833, 11560.768784624428, 9466.668666596635, 11859.58491452795, 11862.347570883685, 11957.282563046245, 11990.04079673624, 5487.533559647474, 5550.833474995757, 5556.861078473071, 5567.415421380697, 5573.191887176336, 927.7495076329353, 942.9768700959536, 982.759223990127, 999.695092996643, 1010.8079464901646, 22882.54283585796, 24680.0, 27874.5818812718, 28272.010479633525, 29005.274868128465, 1057.1595581512715, 1059.3590877519143, 1097.7365432296192, 1100.1144079016308, 1113.204059097749, 11710.88289117115, 11938.1046421517, 12007.699807504881, 12020.09849261309, 12034.63896083119, 359509.0, 361952.0, 362594.0, 362975.0, 365074.0, 48999.775005625146, 49042.75478622584, 49107.50892491101, 49137.754311228215, 49266.26100608494, 1458.2417143085747, 1460.6713489464785, 1503.1281856523408, 1511.1310996177242, 1527.0532269992455, 22500.987455564213, 22549.09803607903, 22562.32313030612, 22648.433789154744, 22764.65853012173, 36452.27095458023, 36493.0, 36529.81735091307, 36532.45201321929, 36557.817210913774, 316617.83382165886, 317165.0, 317688.4115579407, 318605.0, 318826.0, 24674.0, 26908.461830762884, 30229.546556802106, 30906.536401953537, 31009.844950775103, 38034.809825950695, 38081.0, 39143.41284880672, 39234.60765392309, 39341.40987885241, 22528.0, 27260.31849203786, 27895.860520697268, 28029.73866176068, 28317.85841070864, 25248.621270680585, 26900.0, 27555.86222068957, 27698.169054928385, 28086.85956570204, 32065.358692826478, 32776.83611581927, 32808.50787238146, 32820.0, 32924.50613240851, 27279.45441091207, 27437.862810686616, 27944.16167514978, 28269.01058462944, 28741.85629071925, 18289.17698703588, 22523.774762252167, 22894.427639308484, 22898.885505572365, 22915.541689166457], "value": 16782.50868678697, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.9743592260166, 428.6339406476253, 437.9912401751964, 438.90563528841295, 456.79672545717125, 20526.897365513152, 21975.340739777792, 22436.66345004822, 22805.42986425338, 22811.543769124608, 22373.44066398341, 22397.0, 22433.55132897341, 23574.5285094298, 23826.5234695306, 6049.667268300231, 6176.135341052256, 6263.968680156593, 6272.247330320368, 6274.654893980829, 5962.522998160137, 6239.532035097363, 6240.781572644958, 6246.219222597176, 6250.624962502242, 6204.78283260086, 6222.191115155046, 6230.781922632709, 6732.899006514882, 7075.115610548682, 6679.265280819106, 6916.2737912519215, 6990.720371185146, 6997.1603407591165, 7002.124734408199, 6432.421082102634, 6972.895406568906, 6975.093237879043, 6980.7905762827195, 6988.161420629506, 28954.420911581652, 29772.851135744182, 30575.541366879515, 30585.541216881764, 30931.381372372427, 1965.3514340267677, 2209.7127373441513, 2341.519988402373, 2351.106579499779, 2353.0, 18825.435236942914, 18830.71753923692, 19261.940593267365, 19455.02724863751, 19613.80386196134, 2223.310773660154, 2254.6392577187735, 2320.5242925200464, 2323.546908352872, 2329.860208387502, 148362.0, 154505.0, 157570.0, 161274.0, 166108.16945915192, 20834.47913802167, 22092.0, 22141.77858221397, 23619.881900590386, 23971.640425393634, 41785.77677790276, 41862.79068604698, 41911.0, 41934.790326048176, 42038.52865149705, 8791.780205494913, 8870.0, 9040.141186587263, 9168.404053736485, 9242.353035287466, 8652.783680407914, 8664.046954834963, 8665.783355416039, 8841.248493878093, 8853.29173666105, 6481.416672499403, 6496.675166241669, 6913.5506192097355, 7647.808804779925, 8160.327132937734, 1846.510674671213, 2195.1548653768414, 2205.7132572765436, 2208.7349518057936, 2358.4221865643026, 903.3089686389917, 936.7517607833929, 940.8776859008285, 947.613619467719, 972.2610815780058, 19554.9145883581, 20628.381148565568, 20643.8967805163, 20693.17227310921, 21040.684389734168, 956.1681337236648, 960.9039096090474, 980.9019098090276, 997.3317877022413, 1002.5288114586133, 10544.947275263574, 10921.235513513977, 11040.944795275973, 11045.613403530839, 11058.612948546763, 627849.0, 656481.0, 673789.0, 696461.0, 703058.0, 112127.9542420593, 124780.0, 130505.0, 133096.0, 144097.27951360177, 1018.3533456255332, 1032.2670903658486, 1190.5356910804794, 1192.2548407245472, 1193.2184419205435, 21235.150593976687, 21257.255996040065, 21265.149394024684, 21339.573208536054, 21655.566888661822, 60605.69697151486, 61464.0, 62039.6898015507, 62384.44038899064, 62902.74194515993, 76209.61895190488, 79017.0, 85758.14241857502, 92659.0, 93693.59459607975, 8687.478751274944, 8864.246539043996, 8954.373193876367, 8966.506842123747, 9002.774930626525, 11975.28148311104, 13802.792958105436, 13824.516141934984, 14124.576262712135, 14186.219757913414, 11704.414779261173, 11957.282563046245, 11987.64037078889, 12191.51233950632, 12208.0, 10682.344236643332, 11012.119030477383, 11026.283291586022, 11103.500342484445, 11452.94273528627, 10408.219383546113, 11355.829662555234, 11785.646430607096, 12174.39128043612, 12254.387280635754, 8559.0, 8620.568971551524, 8924.921041506108, 8930.330225233003, 9065.63737450521, 11237.494312755782, 11680.007199388147, 11688.363629092104, 11760.88239117598, 11789.115816313639], "value": 11213.15906196597, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.6423329934216, 1450.9056911300765, 1470.536780914012, 1471.161437980351, 1471.2570152073201, 10903.945480272598, 11353.375564343958, 11367.772644547103, 11373.772524549504, 11424.771504569913, 10979.560817567308, 11051.447427628607, 11309.49107290172, 11324.03745681618, 11342.60300889467, 2480.665110210121, 2529.2285852814975, 2536.201096654557, 2536.720960694322, 2536.936576585582, 2255.052877791323, 2302.458922153293, 2514.572522671141, 2516.8364056336372, 2518.8236823422367, 2409.9277021689377, 2510.8242423030397, 2511.0458025950115, 2515.5723527000364, 2516.5973444248925, 2495.887685054177, 2520.2565243253102, 2521.470491196851, 2543.097200493835, 2550.7831834294016, 2480.5410998965067, 2495.837770544927, 2501.562226610336, 2522.8864701088496, 2524.596064629669, 11478.540858365655, 11565.884341156565, 11583.594574189947, 11593.594224202197, 11598.652040438801, 6415.230172379344, 6638.900416493757, 6654.035164901111, 6655.567388119805, 6668.499862510331, 3897.57126716061, 4167.83328666853, 4197.895052623679, 4258.616724494779, 4265.97867010663, 1152.8270759386075, 1156.2888823373648, 1197.7065618923332, 1272.9427175777112, 1276.4064709909844, 14567.0, 15058.774118388343, 15059.698806023927, 15953.361865525365, 16069.0, 5263.552598029134, 5383.67697938125, 5554.2779438672715, 5569.832905012856, 5577.525910297589, 10933.945330273456, 11007.779844403067, 11968.222065565711, 11993.220440671328, 11999.0, 6483.7730679425995, 6483.935160648428, 6489.740410383626, 6493.577917435352, 6498.057781621638, 5843.123531470228, 6013.639181649115, 6017.157597936292, 6019.367966363557, 6117.898778219963, 4436.955630443719, 4476.4180656514445, 4592.081583683277, 4624.491305956342, 4636.86089417318, 1220.4995951659778, 1400.4468235047302, 1437.6190309567974, 1444.5377479206552, 1468.59613606257, 750.8273097187678, 755.3088923634922, 777.9222077792175, 783.8275579372529, 796.8325409717975, 4859.416869975626, 6288.811335659938, 6369.904451433232, 6505.642189679613, 6601.405873471411, 583.8365257727843, 583.9270091238595, 586.1266712598198, 598.87423641035, 602.4698265526309, 3668.560407329096, 4143.958560414358, 4228.450301460852, 4468.597826195658, 4492.752898590609, 13827.654308642364, 14402.783958240634, 14662.120272783666, 14667.92666036663, 14698.706025879423, 14115.85884141166, 14277.42890284377, 14550.636234094232, 14566.85433145655, 14593.270336483134, 10055.497225138713, 10100.242481813893, 10450.68647940563, 10509.159267258601, 10627.574897004033, 5310.9468905312, 6467.159269294962, 6697.799066028027, 6701.396874281338, 6813.386795188457, 11022.228444008842, 11355.318680878843, 12058.698532536757, 12346.074044446883, 12361.567345142877, 12250.0, 12289.0, 12462.688432789253, 12467.87532124667, 12602.369881505705, 8849.0, 9067.818643627223, 9215.216706580015, 9215.539223038955, 9218.354715169873, 10041.347312424668, 10098.444585547866, 10213.08082272599, 10235.385876847413, 10358.274920755775, 8670.566471676517, 8711.346649001223, 8720.12798720123, 8764.956175219084, 8817.382783205114, 8377.664893404195, 8435.072142064246, 8539.316854651737, 8566.700165494427, 8569.614367353608, 7702.152763195932, 7744.0, 7797.727079552188, 7862.488938219226, 7880.3301719354495, 5816.534677225727, 5819.418058194146, 5846.590738648254, 5847.7368518417625, 5975.282966044103, 5915.911261330997, 6022.156898034191, 6033.064874944391, 6033.577649564489, 6098.024316109401], "value": 5231.041068521612, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.3819983525947, 398.2214770124407, 399.6972590067919, 409.9672026237901, 410.6365866208405, 5413.269208656835, 5415.756290966906, 5944.435278648527, 6042.8489287767825, 6074.179985701929, 5746.281714785652, 5772.307323121231, 5796.855078623036, 5824.359320474744, 5842.912356314659, 3746.763568022546, 4476.48520420151, 4492.775361231926, 4512.578537760612, 4555.0, 4287.871363859073, 4513.826405134672, 4548.613367863735, 4551.431071116111, 4557.954420455786, 3762.492063571424, 4263.463239372569, 4493.9775301123445, 4524.276115821469, 4559.062398480649, 4329.610335069859, 4480.955190448087, 4492.415985921809, 4495.213337665912, 4525.50219475859, 3414.3000684859544, 4022.0749227677506, 4026.4564283821896, 4028.315186418316, 4088.4276201331836, 4213.6629069674655, 4808.975955120237, 5583.413741557119, 5692.260006199209, 5885.293764748258, 4679.0, 5374.3013408256675, 5396.64921780083, 5396.92061587684, 5401.621886467949, 5158.767855446514, 6269.673924991741, 6270.937290627081, 6293.244810622755, 6296.905546416808, 4378.489713874501, 4422.911541769146, 4457.1977044132045, 4488.281874900001, 4521.389612402317, 6330.905036424458, 6539.847197029825, 6546.672666366662, 6599.9670001650165, 6605.9339406605795, 4053.8783836484954, 4515.864524064284, 4616.938104235977, 4636.582707556337, 4683.484816670163, 4148.730332528376, 4501.909961800811, 4730.668853180313, 4742.312364707167, 4762.452317983407, 5084.872878178076, 5254.7635356409055, 5341.893162136736, 5351.973240133852, 5399.919001214985, 6075.605085669417, 6260.966940454774, 6304.6847657617855, 6309.526785491106, 6320.336364681734, 1205.2768338996532, 1359.626102821732, 1558.9844101559065, 1565.8669013133783, 1594.8245692973765, 900.2977677411629, 1081.167501024207, 1134.6766171641207, 1137.8236373362142, 1143.279733767721, 4865.391826021747, 5722.37053924068, 5853.560982926298, 5889.352171261158, 5961.986462301419, 5753.568482363839, 5956.031443211453, 6454.612723236621, 6527.184101987252, 6556.27880933097, 4734.834280800155, 5397.109476936262, 5704.942950570441, 5875.559333050039, 5910.7195506493445, 2579.2649095007832, 3067.294522259893, 3240.5949256342956, 3300.745716627714, 3369.983150084283, 6088.634681919099, 6118.969405153035, 6520.4131628152745, 6628.96685516579, 6644.8671026579195, 5010.448850626428, 6070.991432068561, 6071.9981203101, 6582.44049255809, 6597.30728273534, 6588.682263547214, 6611.892410567026, 6612.140421745143, 6622.635755033327, 6642.505436276799, 5087.796488140581, 5401.081816091195, 5589.413111623221, 6431.485481161402, 6449.580777249464, 6028.57799953999, 6325.652089135142, 6345.428911397997, 6358.0, 6422.486201103994, 6077.544184186116, 6392.488600911823, 6478.093066970534, 6553.590977939575, 6584.374484423972, 3946.861859834892, 4357.172137293904, 4389.363542286287, 4636.235021221461, 4643.373144625568, 5300.443453437333, 5397.27136836522, 5486.351576201941, 6208.81373558794, 6385.521085918667, 6255.499560035095, 6329.493640508656, 6338.334474880256, 6339.746410143543, 6340.100837984698, 5620.662760234235, 6126.661794046234, 6267.4986001120715, 6285.962816135287, 6296.748130074746, 6116.633002019893, 6150.261968563801, 6156.138140660221, 6176.722047507963, 6206.6345404011045, 5938.287405511366, 5972.58191926561, 6287.497000240061, 6292.2134733158355, 6294.8111556653375, 5986.700664966821, 6035.732496175677, 6132.264128304632, 6167.907481387693, 6175.135481032748], "value": 4621.623376606063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.7735124760077, 728.3190217146968, 807.4105902691032, 833.7707130539097, 842.2335674536173, 15663.451779187732, 15812.44656437025, 16000.599985000381, 16155.515334539969, 16721.414750483735, 13228.735425291488, 15278.923605381957, 15404.537863864101, 15451.22743862808, 16601.750973735405, 964.7298756348199, 1000.2698030437784, 1002.9297949143563, 1004.3772860826261, 1004.9296549241556, 953.7567920180344, 994.9204063674889, 1005.0552480668185, 1008.1027885182157, 1013.8681971343715, 1049.8687664041995, 1061.2624226162782, 1062.760878802271, 1063.9892102502654, 1066.8506409102731, 1044.3107549017689, 1047.539082803564, 1047.8009178256107, 1049.559185142242, 1059.6291298045699, 775.5153029356652, 777.564563844249, 778.2023425988351, 778.7079845058104, 779.0067663728792, 16510.587235319093, 16948.3220671173, 16977.405790797264, 17034.400711807742, 17320.307187712475, 14709.338079786328, 14724.33740481681, 14789.40842366304, 14829.703405931821, 14852.70294594102, 13274.336283185801, 13386.330683465787, 13432.529861454803, 13524.729505409936, 13630.93184534071, 2918.693537178608, 3325.700686938163, 3356.060303115132, 3368.444206705891, 3371.039253812676, 120461.39769301185, 122915.0, 123214.38392808069, 124796.37601812024, 124925.37537312346, 11458.828117578243, 11520.868639301589, 11542.538298467967, 11740.70648233801, 11958.58144964922, 22528.887355563118, 25121.0, 25472.599007054418, 25575.104871329786, 25622.743772562037, 7882.330001949784, 8118.35053195743, 8120.269175774209, 8216.014078310642, 8217.383696222807, 4107.9178416431505, 4231.407602935591, 4236.152769446123, 4275.080857615565, 4323.135372925428, 4035.2534781065597, 4097.8975525612095, 4150.377443383456, 4152.231837110145, 4157.729747566398, 720.9567425954355, 843.2031730015077, 849.3884403229665, 851.6082602003024, 855.0252711908429, 548.1687086199734, 562.794579978305, 608.8021393047277, 618.9381061893774, 633.4805459523238, 9425.19885809714, 9803.607855685836, 9906.702798916043, 9958.094178864332, 10068.194544436428, 553.8144721518337, 600.7356763024255, 602.2420098584886, 616.2758758458793, 654.6890227142164, 4409.603135717801, 4544.909101817945, 4563.589276965089, 4608.358979450001, 4660.577586164117, 101507.0, 105637.0, 106069.0, 107077.46461267644, 107345.4632726847, 94765.52617237008, 95870.0, 98181.0, 98366.0, 98896.50551747195, 56744.26447395815, 67701.66149169223, 72351.0, 73923.99999999892, 78020.0, 9942.801143976936, 9999.35004224723, 10623.256372054191, 10734.248602598056, 10758.62344817959, 36477.0, 38230.39310780469, 38568.0, 39247.41128883126, 39348.213035739696, 36340.45489317715, 50932.472025839284, 51365.486345137564, 51600.0, 52628.47371526236, 12962.416691249105, 13059.0, 13130.868691313346, 13425.462981480632, 13841.51554695621, 14072.718545629235, 14080.577582672537, 14429.567112986628, 14559.636009099857, 15126.697466050397, 12778.808317875053, 12965.870341296844, 13131.212127272394, 13149.276789776653, 13512.391942362421, 12320.137590368946, 12510.085984565321, 12510.856652257655, 12529.0, 13055.281959492318, 12224.379870308383, 12332.938335308267, 12405.317707526172, 12488.937555312164, 12900.29048402347, 8822.358864147434, 9121.035663409286, 9183.448993060438, 9384.390014649027, 9545.31602060348, 10932.453377331261, 11053.502592383524, 11106.833397499206, 11241.156913231704, 11250.493727782106], "value": 7662.62402048525, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1615.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 124.0, 123.0, 125.0, 124.0, 123.0, 121.0, 121.0, 124.0, 122.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 121.0, 118.0, 162.0, 127.0, 123.0, 115.0, 119.0, 144.0, 118.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 212.0, 214.0, 208.0, 212.0, 291.0, 250.0, 211.0, 212.0, 210.0], "value": 223.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 66.0, 65.0, 102.0, 68.0, 65.0, 63.0, 64.0, 81.0, 62.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 95.0, 61.0, 94.0, 112.0, 133.0, 75.0, 72.0, 92.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 94.0, 96.0, 117.0, 92.0, 92.0, 92.0, 93.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 100.0, 100.0, 100.0, 101.0, 100.0, 101.0, 100.0, 99.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 100.0, 102.0, 101.0, 101.0, 102.0, 102.0, 102.0, 100.0, 131.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 51.0, 51.0, 51.0, 51.0, 53.0, 50.0, 51.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 72.0, 73.0, 70.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 90.0, 92.0, 90.0, 94.0, 110.0, 117.0, 116.0, 88.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 159.0, 161.0, 174.0, 172.0, 170.0, 151.0, 150.0, 193.0, 150.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 203.0, 211.0, 206.0, 202.0, 204.0, 238.0, 273.0, 198.0, 199.0], "value": 213.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 93.0, 67.0, 68.0, 82.0, 112.0, 107.0, 62.0, 89.0], "value": 82.9, "unit": "score"}]}]} 10:41:06 INFO - Return code: 0 10:41:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:41:06 ERROR - # TBPL SUCCESS # 10:41:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:41:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:41:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:41:06 INFO - 2017-04-07 10:41:06.715 screenresolution[2444:24631] starting screenresolution argv=screenresolution get 10:41:06 INFO - 2017-04-07 10:41:06.718 screenresolution[2444:24631] Display 0: 1600x1200x32@60 10:41:06 INFO - 2017-04-07 10:41:06.724 screenresolution[2445:24634] starting screenresolution argv=screenresolution list 10:41:06 INFO - Available Modes on Display 0 10:41:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:41:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:41:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:41:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:41:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:41:06 INFO - Intel Iris: 10:41:06 INFO - Chipset Model: Intel Iris 10:41:06 INFO - Type: GPU 10:41:06 INFO - Bus: Built-In 10:41:06 INFO - VRAM (Dynamic, Max): 1536 MB 10:41:06 INFO - Vendor: Intel (0x8086) 10:41:06 INFO - Device ID: 0x0a2e 10:41:06 INFO - Revision ID: 0x0009 10:41:06 INFO - Displays: 10:41:06 INFO - Display: 10:41:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:41:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:41:06 INFO - Display Serial Number: 1600x1200 60 10:41:06 INFO - Main Display: Yes 10:41:06 INFO - Mirror: Off 10:41:06 INFO - Online: Yes 10:41:06 INFO - Rotation: Supported 10:41:06 INFO - Return code: 0 10:41:06 INFO - Running post-action listener: _resource_record_post_action 10:41:06 INFO - [mozharness: 2017-04-07 17:41:06.959888Z] Finished run-tests step (success) 10:41:06 INFO - Running post-run listener: _resource_record_post_run 10:41:07 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 225280; Write bytes: 518476800; Read time: 2; Write time: 1604 10:41:07 INFO - TinderboxPrint: CPU usage
53.4% 10:41:07 INFO - TinderboxPrint: I/O read bytes / time
225,280 / 2 10:41:07 INFO - TinderboxPrint: I/O write bytes / time
518,476,800 / 1,604 10:41:07 INFO - TinderboxPrint: CPU idle
2,250.7 (46.6%) 10:41:07 INFO - TinderboxPrint: CPU system
962.2 (19.9%) 10:41:07 INFO - TinderboxPrint: CPU user
1,612.7 (33.4%) 10:41:07 INFO - TinderboxPrint: Swap in / out
207,003,648 / 0 10:41:07 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206705152; Write bytes: 203974656; Read time: 16037; Write time: 448 10:41:07 INFO - run-tests - Wall time: 1187s; CPU: 53.0%; Read bytes: 225280; Write bytes: 307379200; Read time: 2; Write time: 1123 10:41:07 INFO - Running post-run listener: _upload_blobber_files 10:41:07 INFO - Blob upload gear active. 10:41:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:41:07 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 10:41:07 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'] 10:41:07 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 10:41:07 INFO - (blobuploader) - INFO - Open directory for files ... 10:41:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:41:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:41:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:08 INFO - (blobuploader) - INFO - Done attempting. 10:41:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:41:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:41:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:09 INFO - (blobuploader) - INFO - Done attempting. 10:41:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:41:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:41:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:10 INFO - (blobuploader) - INFO - Done attempting. 10:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:12 INFO - (blobuploader) - INFO - Done attempting. 10:41:12 INFO - (blobuploader) - INFO - Iteration through files over. 10:41:12 INFO - Return code: 0 10:41:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:41:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:41:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7753db9df0b9e42cdb0cf64f4e726f306ec51c794a951c32b899891861a19bd61362aef3596efa6f5c251ce5c2e949f16c32025b8702f005813ac5168b2547ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1f72cc8f56dc76a36c1d4299d25bbf6849924a7972c25428034ed4ba43631b3f1e60792f89b6f790870a8e5b83f3f7de6939109ae86f3abdafa7e42cee98ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51d203020f3d10fb9773496b3ef643287fb8ec6a81d19a0dba907c97192d500134dffec0841d674ae10aa9d5eeb1566efd3c6311e610940616ca8c8fdb87f24c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cff77f69d8a600810588aa6f25fab1bb706636e425cc7a111b34acfefe1467817c32c5d56612bbaba235c5e1b7ff5ac47c345a423205c8e3cef7f8af470a6"} 10:41:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:41:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:41:12 INFO - Contents: 10:41:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7753db9df0b9e42cdb0cf64f4e726f306ec51c794a951c32b899891861a19bd61362aef3596efa6f5c251ce5c2e949f16c32025b8702f005813ac5168b2547ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1f72cc8f56dc76a36c1d4299d25bbf6849924a7972c25428034ed4ba43631b3f1e60792f89b6f790870a8e5b83f3f7de6939109ae86f3abdafa7e42cee98ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51d203020f3d10fb9773496b3ef643287fb8ec6a81d19a0dba907c97192d500134dffec0841d674ae10aa9d5eeb1566efd3c6311e610940616ca8c8fdb87f24c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cff77f69d8a600810588aa6f25fab1bb706636e425cc7a111b34acfefe1467817c32c5d56612bbaba235c5e1b7ff5ac47c345a423205c8e3cef7f8af470a6"} 10:41:12 INFO - Running post-run listener: copy_logs_to_upload_dir 10:41:12 INFO - Copying logs to upload dir... 10:41:12 INFO - mkdir: /builds/slave/test/build/upload/logs 10:41:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.659889 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-07 10:41:12.844385) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:12.847709) ========= 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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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/7753db9df0b9e42cdb0cf64f4e726f306ec51c794a951c32b899891861a19bd61362aef3596efa6f5c251ce5c2e949f16c32025b8702f005813ac5168b2547ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1f72cc8f56dc76a36c1d4299d25bbf6849924a7972c25428034ed4ba43631b3f1e60792f89b6f790870a8e5b83f3f7de6939109ae86f3abdafa7e42cee98ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51d203020f3d10fb9773496b3ef643287fb8ec6a81d19a0dba907c97192d500134dffec0841d674ae10aa9d5eeb1566efd3c6311e610940616ca8c8fdb87f24c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cff77f69d8a600810588aa6f25fab1bb706636e425cc7a111b34acfefe1467817c32c5d56612bbaba235c5e1b7ff5ac47c345a423205c8e3cef7f8af470a6"} build_url:https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7753db9df0b9e42cdb0cf64f4e726f306ec51c794a951c32b899891861a19bd61362aef3596efa6f5c251ce5c2e949f16c32025b8702f005813ac5168b2547ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1f72cc8f56dc76a36c1d4299d25bbf6849924a7972c25428034ed4ba43631b3f1e60792f89b6f790870a8e5b83f3f7de6939109ae86f3abdafa7e42cee98ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51d203020f3d10fb9773496b3ef643287fb8ec6a81d19a0dba907c97192d500134dffec0841d674ae10aa9d5eeb1566efd3c6311e610940616ca8c8fdb87f24c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cff77f69d8a600810588aa6f25fab1bb706636e425cc7a111b34acfefe1467817c32c5d56612bbaba235c5e1b7ff5ac47c345a423205c8e3cef7f8af470a6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:12.883080) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:12.883464) ========= 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.kJ71lrdPtJ/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.L1Hu7RMH6h/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005325 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:12.933293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:41:12.933634) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:41:12.933983) ========= ========= Total master_lag: 0.20 =========