builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1490483439.91 results: success (0) buildid: 20170325153315 builduid: b2986aec16c842ca9b629c0b7a97e963 revision: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:39.913402) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:39.913808) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:39.914092) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:39.943596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:39.943917) ========= 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.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-03-25 16:10:39-- 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% 13.9M=0.001s 2017-03-25 16:10:40 (13.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.464215 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:40.432108) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:40.432418) ========= 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.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.029331 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:40.483275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:40.483614) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2ecf610d3185de940cb7b4ed2c214dc9e2367cee --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 2ecf610d3185de940cb7b4ed2c214dc9e2367cee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-03-25 16:10:40,589 truncating revision to first 12 chars 2017-03-25 16:10:40,589 Setting DEBUG logging. 2017-03-25 16:10:40,589 attempt 1/10 2017-03-25 16:10:40,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2ecf610d3185?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-25 16:10:41,265 unpacking tar archive at: mozilla-central-2ecf610d3185/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965585 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:41.472600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:41.472909) ========= script_repo_revision: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:41.473290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:41.473573) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:41.493275) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-25 16:10:41.493547) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:10:41 INFO - MultiFileLogger online at 20170325 16:10:41 in /builds/slave/test 16:10:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:10:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:41 INFO - {'append_to_log': False, 16:10:41 INFO - 'base_work_dir': '/builds/slave/test', 16:10:41 INFO - 'blob_upload_branch': 'Firefox', 16:10:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:41 INFO - 'branch': 'Firefox', 16:10:41 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:41 INFO - 'config_files': ('talos/mac_config.py',), 16:10:41 INFO - 'default_actions': ('clobber', 16:10:41 INFO - 'read-buildbot-config', 16:10:41 INFO - 'download-and-extract', 16:10:41 INFO - 'populate-webroot', 16:10:41 INFO - 'create-virtualenv', 16:10:41 INFO - 'install', 16:10:41 INFO - 'run-tests'), 16:10:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:41 INFO - 'download_minidump_stackwalk': True, 16:10:41 INFO - 'download_symbols': 'ondemand', 16:10:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:41 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:41 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:41 INFO - 'gecko_profile': False, 16:10:41 INFO - 'gecko_profile_interval': 0, 16:10:41 INFO - 'installer_path': 'installer.exe', 16:10:41 INFO - 'log_level': 'info', 16:10:41 INFO - 'log_name': 'talos', 16:10:41 INFO - 'log_to_console': True, 16:10:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:41 INFO - 'opt_config_files': (), 16:10:41 INFO - 'pip_index': False, 16:10:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:41 INFO - 'cmd': ('bash', 16:10:41 INFO - '-c', 16:10:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:41 INFO - 'enabled': True, 16:10:41 INFO - 'halt_on_failure': False, 16:10:41 INFO - 'name': 'check_screen_resolution'},), 16:10:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:41 INFO - 'cmd': ('bash', 16:10:41 INFO - '-c', 16:10:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:41 INFO - 'enabled': True, 16:10:41 INFO - 'halt_on_failure': False, 16:10:41 INFO - 'name': 'check_screen_resolution'},), 16:10:41 INFO - 'run_cmd_checks_enabled': True, 16:10:41 INFO - 'suite': 'dromaeojs-e10s', 16:10:41 INFO - 'system_bits': '32', 16:10:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:41 INFO - 'title': 't-yosemite-r7-0195', 16:10:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:41 INFO - 'use_talos_json': True, 16:10:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:41 INFO - 'work_dir': 'build'} 16:10:41 INFO - [mozharness: 2017-03-25 23:10:41.713354Z] Running clobber step. 16:10:41 INFO - Running pre-action listener: _resource_record_pre_action 16:10:41 INFO - Running main action method: clobber 16:10:41 INFO - rmtree: /builds/slave/test/build 16:10:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:44 INFO - Running post-action listener: _resource_record_post_action 16:10:44 INFO - [mozharness: 2017-03-25 23:10:44.332173Z] Finished clobber step (success) 16:10:44 INFO - [mozharness: 2017-03-25 23:10:44.332306Z] Running read-buildbot-config step. 16:10:44 INFO - Running pre-action listener: _resource_record_pre_action 16:10:44 INFO - Running main action method: read_buildbot_config 16:10:44 INFO - Using buildbot properties: 16:10:44 INFO - { 16:10:44 INFO - "project": "", 16:10:44 INFO - "product": "firefox", 16:10:44 INFO - "script_repo_revision": "production", 16:10:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:10:44 INFO - "repository": "", 16:10:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:10:44 INFO - "buildid": "20170325153315", 16:10:44 INFO - "pgo_build": "False", 16:10:44 INFO - "basedir": "/builds/slave/test", 16:10:44 INFO - "buildnumber": 3, 16:10:44 INFO - "slavename": "t-yosemite-r7-0195", 16:10:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:10:44 INFO - "platform": "yosemite_r7", 16:10:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:10:44 INFO - "branch": "mozilla-central", 16:10:44 INFO - "repo_path": "mozilla-central", 16:10:44 INFO - "revision": "2ecf610d3185de940cb7b4ed2c214dc9e2367cee", 16:10:44 INFO - "stage_platform": "macosx64", 16:10:44 INFO - "builduid": "b2986aec16c842ca9b629c0b7a97e963", 16:10:44 INFO - "slavebuilddir": "test" 16:10:44 INFO - } 16:10:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:10:44 INFO - Running post-action listener: _resource_record_post_action 16:10:44 INFO - [mozharness: 2017-03-25 23:10:44.335969Z] Finished read-buildbot-config step (success) 16:10:44 INFO - [mozharness: 2017-03-25 23:10:44.336080Z] Running download-and-extract step. 16:10:44 INFO - Running pre-action listener: _resource_record_pre_action 16:10:44 INFO - Running main action method: download_and_extract 16:10:44 INFO - mkdir: /builds/slave/test/build/tests 16:10: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')]} 16:10:44 INFO - trying https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:44 INFO - Downloading https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:10:45 INFO - Downloaded 1325 bytes. 16:10:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:45 INFO - Using the following test package requirements: 16:10:45 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:10:45 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:45 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:10:45 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:10:45 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'jsshell-mac.zip'], 16:10:45 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:10:45 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:45 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:10:45 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:10:45 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:10:45 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:45 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:10:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:10:47 INFO - Expected file size: 52411811 16:10:47 INFO - Obtained file size: 52411811 16:11:03 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 16:11:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:11:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:11:03 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:11:05 INFO - Expected file size: 52411811 16:11:05 INFO - Obtained file size: 52411811 16:11:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:11:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:11:20 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:11:21 INFO - Expected file size: 14404171 16:11:21 INFO - Obtained file size: 14404171 16:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:21 INFO - trying https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:21 INFO - Downloading https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:11:24 INFO - Downloaded 75592109 bytes. 16:11:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:24 INFO - mkdir: /builds/slave/test/properties 16:11:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:11:24 INFO - Writing to file /builds/slave/test/properties/build_url 16:11:24 INFO - Contents: 16:11:24 INFO - build_url:https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:24 INFO - Running post-action listener: _resource_record_post_action 16:11:24 INFO - Running post-action listener: set_extra_try_arguments 16:11:24 INFO - [mozharness: 2017-03-25 23:11:24.809604Z] Finished download-and-extract step (success) 16:11:24 INFO - [mozharness: 2017-03-25 23:11:24.809723Z] Running populate-webroot step. 16:11:24 INFO - Running pre-action listener: _resource_record_pre_action 16:11:24 INFO - Running main action method: populate_webroot 16:11:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:11:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:11:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:11:24 INFO - '--tppagecycles', 16:11:24 INFO - '7'], 16:11:24 INFO - 'tests': ['tsvgm']}}, 16:11:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:11:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:11:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:11:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:11:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['damp', 'tps']}, 16:11:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'tests': ['damp', 'tps']}, 16:11:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['dromaeo_dom']}, 16:11:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:11:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['basic_compositor_video']}, 16:11:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:11:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'other-e10s': {'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['a11yr', 16:11:24 INFO - 'ts_paint', 16:11:24 INFO - 'tpaint', 16:11:24 INFO - 'sessionrestore', 16:11:24 INFO - 'sessionrestore_no_auto_restore', 16:11:24 INFO - 'tabpaint']}, 16:11:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['tsvgx', 16:11:24 INFO - 'tsvgr_opacity', 16:11:24 INFO - 'tart', 16:11:24 INFO - 'tscrollx', 16:11:24 INFO - 'cart', 16:11:24 INFO - 'tsvg_static']}, 16:11:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:11:24 INFO - 'tsvgr_opacity', 16:11:24 INFO - 'tart', 16:11:24 INFO - 'tscrollx', 16:11:24 INFO - 'cart', 16:11:24 INFO - 'tsvg_static']}, 16:11:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'talos_options': ['--disable-e10s'], 16:11:24 INFO - 'tests': ['tp5o']}, 16:11:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:11:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'talos_options': ['--disable-e10s', 16:11:24 INFO - '--xperf_path', 16:11:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:24 INFO - 'tests': ['tp5n']}, 16:11:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:11:24 INFO - 'talos_options': ['--xperf_path', 16:11:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:24 INFO - 'tests': ['tp5n']}}, 16:11:24 INFO - 'talos.zip': {'path': '', 16:11:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:11:24 INFO - Running post-action listener: _resource_record_post_action 16:11:24 INFO - [mozharness: 2017-03-25 23:11:24.827946Z] Finished populate-webroot step (success) 16:11:24 INFO - [mozharness: 2017-03-25 23:11:24.828026Z] Running create-virtualenv step. 16:11:24 INFO - Running pre-action listener: _resource_record_pre_action 16:11:24 INFO - Running main action method: create_virtualenv 16:11:24 INFO - Creating virtualenv /builds/slave/test/build/venv 16:11:24 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 16:11:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:11:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:11:24 INFO - Using real prefix '/tools/python27' 16:11:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:11:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:11:28 INFO - Installing pip.................done. 16:11:28 INFO - Return code: 0 16:11:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:11:28 INFO - Reading from file tmpfile_stdout 16:11:28 INFO - Output received: 16:11:28 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) 16:11:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:11:28 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')]} 16:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:28 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')]} 16:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:28 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:28 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 16:11:28 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 16:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:28 INFO - 'HOME': '/Users/cltbld', 16:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:28 INFO - 'LOGNAME': 'cltbld', 16:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:28 INFO - 'MOZ_NO_REMOTE': '1', 16:11:28 INFO - 'NO_EM_RESTART': '1', 16:11:28 INFO - 'PAGER': '/bin/cat', 16:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:28 INFO - 'PWD': '/builds/slave/test', 16:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:28 INFO - 'SHELL': '/bin/bash', 16:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:28 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:28 INFO - 'USER': 'cltbld', 16:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:28 INFO - 'XPC_FLAGS': '0x0', 16:11:28 INFO - 'XPC_SERVICE_NAME': '0', 16:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:29 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 16:11:29 INFO - Cleaning up... 16:11:29 INFO - Return code: 0 16:11:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:29 INFO - 'HOME': '/Users/cltbld', 16:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:29 INFO - 'LOGNAME': 'cltbld', 16:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:29 INFO - 'MOZ_NO_REMOTE': '1', 16:11:29 INFO - 'NO_EM_RESTART': '1', 16:11:29 INFO - 'PAGER': '/bin/cat', 16:11:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:29 INFO - 'PWD': '/builds/slave/test', 16:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:29 INFO - 'SHELL': '/bin/bash', 16:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:29 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:29 INFO - 'USER': 'cltbld', 16:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:29 INFO - 'XPC_FLAGS': '0x0', 16:11:29 INFO - 'XPC_SERVICE_NAME': '0', 16:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:29 INFO - Downloading/unpacking psutil>=3.1.1 16:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:11:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:33 INFO - Installing collected packages: psutil 16:11:33 INFO - Running setup.py install for psutil 16:11:33 INFO - building 'psutil._psutil_osx' extension 16:11:33 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 16:11:33 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 16:11:34 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 16:11:34 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 16:11:34 INFO - building 'psutil._psutil_posix' extension 16:11:34 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 16:11:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:11:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:11:34 INFO - ^ 16:11:34 INFO - 1 warning generated. 16:11:34 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 16:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:34 INFO - Successfully installed psutil 16:11:34 INFO - Cleaning up... 16:11:34 INFO - Return code: 0 16:11:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11:34 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')]} 16:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 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')]} 16:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:34 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 16:11:34 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 16:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:34 INFO - 'HOME': '/Users/cltbld', 16:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:34 INFO - 'LOGNAME': 'cltbld', 16:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:34 INFO - 'MOZ_NO_REMOTE': '1', 16:11:34 INFO - 'NO_EM_RESTART': '1', 16:11:34 INFO - 'PAGER': '/bin/cat', 16:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:34 INFO - 'PWD': '/builds/slave/test', 16:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:34 INFO - 'SHELL': '/bin/bash', 16:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:34 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:34 INFO - 'USER': 'cltbld', 16:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:34 INFO - 'XPC_FLAGS': '0x0', 16:11:34 INFO - 'XPC_SERVICE_NAME': '0', 16:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11:34 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 16:11:34 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 16:11:34 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 16:11:34 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 16:11:34 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 16:11:34 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 16:11:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:37 INFO - Installing collected packages: mozsystemmonitor 16:11:37 INFO - Running setup.py install for mozsystemmonitor 16:11:37 INFO - Successfully installed mozsystemmonitor 16:11:37 INFO - Cleaning up... 16:11:37 INFO - Return code: 0 16:11:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:37 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:37 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 16:11:37 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 16:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:37 INFO - 'HOME': '/Users/cltbld', 16:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:37 INFO - 'LOGNAME': 'cltbld', 16:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:37 INFO - 'MOZ_NO_REMOTE': '1', 16:11:37 INFO - 'NO_EM_RESTART': '1', 16:11:37 INFO - 'PAGER': '/bin/cat', 16:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:37 INFO - 'PWD': '/builds/slave/test', 16:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:37 INFO - 'SHELL': '/bin/bash', 16:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:37 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:37 INFO - 'USER': 'cltbld', 16:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:37 INFO - 'XPC_FLAGS': '0x0', 16:11:37 INFO - 'XPC_SERVICE_NAME': '0', 16:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:37 INFO - Downloading/unpacking jsonschema==2.5.1 16:11:37 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 16:11:37 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 16:11:37 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 16:11:37 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 16:11:37 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 16:11:37 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 16:11:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:40 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 16:11:40 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 16:11:40 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 16:11:40 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 16:11:40 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 16:11:40 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 16:11:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:41 INFO - warning: no files found matching '*.txt' 16:11:41 INFO - no previously-included directories found matching 'build' 16:11:41 INFO - no previously-included directories found matching 'dist' 16:11:41 INFO - no previously-included directories found matching '.git*' 16:11:41 INFO - Installing collected packages: jsonschema, functools32 16:11:41 INFO - Running setup.py install for functools32 16:11:41 INFO - warning: no files found matching '*.txt' 16:11:41 INFO - no previously-included directories found matching 'build' 16:11:41 INFO - no previously-included directories found matching 'dist' 16:11:41 INFO - no previously-included directories found matching '.git*' 16:11:41 INFO - Successfully installed jsonschema functools32 16:11:41 INFO - Cleaning up... 16:11:41 INFO - Return code: 0 16:11:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11:41 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')]} 16:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:41 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')]} 16:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:41 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:41 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 16:11:41 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 16:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:41 INFO - 'HOME': '/Users/cltbld', 16:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:41 INFO - 'LOGNAME': 'cltbld', 16:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:41 INFO - 'MOZ_NO_REMOTE': '1', 16:11:41 INFO - 'NO_EM_RESTART': '1', 16:11:41 INFO - 'PAGER': '/bin/cat', 16:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:41 INFO - 'PWD': '/builds/slave/test', 16:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:41 INFO - 'SHELL': '/bin/bash', 16:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:41 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:41 INFO - 'USER': 'cltbld', 16:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:41 INFO - 'XPC_FLAGS': '0x0', 16:11:41 INFO - 'XPC_SERVICE_NAME': '0', 16:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:41 INFO - Cleaning up... 16:11:41 INFO - Return code: 0 16:11:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11:41 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')]} 16:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:41 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')]} 16:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:41 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:41 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 16:11:41 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 16:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:41 INFO - 'HOME': '/Users/cltbld', 16:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:41 INFO - 'LOGNAME': 'cltbld', 16:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:41 INFO - 'MOZ_NO_REMOTE': '1', 16:11:41 INFO - 'NO_EM_RESTART': '1', 16:11:41 INFO - 'PAGER': '/bin/cat', 16:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:41 INFO - 'PWD': '/builds/slave/test', 16:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:41 INFO - 'SHELL': '/bin/bash', 16:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:41 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:41 INFO - 'USER': 'cltbld', 16:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:41 INFO - 'XPC_FLAGS': '0x0', 16:11:41 INFO - 'XPC_SERVICE_NAME': '0', 16:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:11:41 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 16:11:41 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 16:11:41 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 16:11:41 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 16:11:41 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 16:11:41 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 16:11:44 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:45 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 16:11:45 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 16:11:45 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 16:11:45 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 16:11:45 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 16:11:45 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 16:11:45 INFO - Downloading docopt-0.6.1.tar.gz 16:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:46 INFO - Running setup.py install for blobuploader 16:11:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:46 INFO - Running setup.py install for requests 16:11:46 INFO - Running setup.py install for docopt 16:11:46 INFO - Successfully installed blobuploader requests docopt 16:11:46 INFO - Cleaning up... 16:11:46 INFO - Return code: 0 16:11:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:46 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:46 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 16:11:46 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 16:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:46 INFO - 'HOME': '/Users/cltbld', 16:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:46 INFO - 'LOGNAME': 'cltbld', 16:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:46 INFO - 'MOZ_NO_REMOTE': '1', 16:11:46 INFO - 'NO_EM_RESTART': '1', 16:11:46 INFO - 'PAGER': '/bin/cat', 16:11:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:46 INFO - 'PWD': '/builds/slave/test', 16:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:46 INFO - 'SHELL': '/bin/bash', 16:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:46 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:46 INFO - 'USER': 'cltbld', 16:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:46 INFO - 'XPC_FLAGS': '0x0', 16:11:46 INFO - 'XPC_SERVICE_NAME': '0', 16:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-2uWyP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-sCSgVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hNE4s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-O1CTS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cMumea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-_6hnOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-HBrYhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-aojbIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:47 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ipwOBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cZwJPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-oxaMar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1dLWrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ul_k2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6shWb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-kt7nNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-NmlTMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:48 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UHzKDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:48 INFO - Running setup.py install for manifestparser 16:11:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:49 INFO - Running setup.py install for mozcrash 16:11:49 INFO - Running setup.py install for mozdebug 16:11:49 INFO - Running setup.py install for mozdevice 16:11:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:49 INFO - Running setup.py install for mozfile 16:11:49 INFO - Running setup.py install for mozhttpd 16:11:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:49 INFO - Running setup.py install for mozinfo 16:11:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:49 INFO - Running setup.py install for mozInstall 16:11:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Running setup.py install for mozleak 16:11:50 INFO - Running setup.py install for mozlog 16:11:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Running setup.py install for moznetwork 16:11:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Running setup.py install for mozprocess 16:11:50 INFO - Running setup.py install for mozprofile 16:11:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:50 INFO - Running setup.py install for mozrunner 16:11:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:51 INFO - Running setup.py install for mozscreenshot 16:11:51 INFO - Running setup.py install for moztest 16:11:51 INFO - Running setup.py install for mozversion 16:11:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:51 INFO - Cleaning up... 16:11:51 INFO - Return code: 0 16:11:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:51 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:51 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 16:11:51 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 16:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:51 INFO - 'HOME': '/Users/cltbld', 16:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:51 INFO - 'LOGNAME': 'cltbld', 16:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:51 INFO - 'MOZ_NO_REMOTE': '1', 16:11:51 INFO - 'NO_EM_RESTART': '1', 16:11:51 INFO - 'PAGER': '/bin/cat', 16:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:51 INFO - 'PWD': '/builds/slave/test', 16:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:51 INFO - 'SHELL': '/bin/bash', 16:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:51 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:51 INFO - 'USER': 'cltbld', 16:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:51 INFO - 'XPC_FLAGS': '0x0', 16:11:51 INFO - 'XPC_SERVICE_NAME': '0', 16:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:51 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-IdJWiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:51 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)) 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:51 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-HSlNVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:51 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)) 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:51 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bmvK_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-P3Fj3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XfP0lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XTSxUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-N1nXQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-aftB1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-zyz50M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-3ThMna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-5jLITs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:52 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-onDvjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:52 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)) 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:53 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-v4nsqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:53 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)) 16:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:53 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Q5Fq3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:53 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)) 16:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:53 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-nXDSVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:53 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)) 16:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:53 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-IOEx3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:53 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)) 16:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:53 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-jLEJl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:53 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)) 16:11:53 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)) 16:11:53 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)) 16:11:53 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)) 16:11:53 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:53 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:56 INFO - Downloading blessings-1.6.tar.gz 16:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:11:56 INFO - Installing collected packages: blessings 16:11:56 INFO - Running setup.py install for blessings 16:11:56 INFO - Successfully installed blessings 16:11:56 INFO - Cleaning up... 16:11:56 INFO - Return code: 0 16:11:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:56 INFO - Reading from file tmpfile_stdout 16:11:56 INFO - Current package versions: 16:11:56 INFO - blessings == 1.6 16:11:56 INFO - blobuploader == 1.2.4 16:11:56 INFO - docopt == 0.6.1 16:11:56 INFO - functools32 == 3.2.3-2 16:11:56 INFO - jsonschema == 2.5.1 16:11:56 INFO - manifestparser == 1.1 16:11:56 INFO - mozInstall == 1.12 16:11:56 INFO - mozcrash == 1.0 16:11:56 INFO - mozdebug == 0.1 16:11:56 INFO - mozdevice == 0.49 16:11:56 INFO - mozfile == 1.2 16:11:56 INFO - mozhttpd == 0.7 16:11:56 INFO - mozinfo == 0.9 16:11:56 INFO - mozleak == 0.1 16:11:56 INFO - mozlog == 3.4 16:11:56 INFO - moznetwork == 0.27 16:11:56 INFO - mozprocess == 0.25 16:11:56 INFO - mozprofile == 0.28 16:11:56 INFO - mozrunner == 6.13 16:11:56 INFO - mozscreenshot == 0.1 16:11:56 INFO - mozsystemmonitor == 0.3 16:11:56 INFO - moztest == 0.8 16:11:56 INFO - mozversion == 1.4 16:11:56 INFO - psutil == 3.1.1 16:11:56 INFO - requests == 1.2.3 16:11:56 INFO - wsgiref == 0.1.2 16:11:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:56 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:56 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 16:11:56 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 16:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:56 INFO - 'HOME': '/Users/cltbld', 16:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:56 INFO - 'LOGNAME': 'cltbld', 16:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:56 INFO - 'MOZ_NO_REMOTE': '1', 16:11:56 INFO - 'NO_EM_RESTART': '1', 16:11:56 INFO - 'PAGER': '/bin/cat', 16:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:56 INFO - 'PWD': '/builds/slave/test', 16:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:56 INFO - 'SHELL': '/bin/bash', 16:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:56 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:56 INFO - 'USER': 'cltbld', 16:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:56 INFO - 'XPC_FLAGS': '0x0', 16:11:56 INFO - 'XPC_SERVICE_NAME': '0', 16:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 INFO - Cleaning up... 16:11:57 INFO - Return code: 0 16:11:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:57 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 0x1026d3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a12d78fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, '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.cjFHichNm2/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.Q7v7wXAi4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 16:11:57 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 16:11:57 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 16:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:57 INFO - 'HOME': '/Users/cltbld', 16:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:57 INFO - 'LOGNAME': 'cltbld', 16:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:57 INFO - 'MOZ_NO_REMOTE': '1', 16:11:57 INFO - 'NO_EM_RESTART': '1', 16:11:57 INFO - 'PAGER': '/bin/cat', 16:11:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:57 INFO - 'PWD': '/builds/slave/test', 16:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:57 INFO - 'SHELL': '/bin/bash', 16:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:11:57 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:57 INFO - 'USER': 'cltbld', 16:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:57 INFO - 'XPC_FLAGS': '0x0', 16:11:57 INFO - 'XPC_SERVICE_NAME': '0', 16:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:11:57 INFO - Cleaning up... 16:11:57 INFO - Return code: 0 16:11:57 INFO - Running post-action listener: _resource_record_post_action 16:11:57 INFO - Running post-action listener: _start_resource_monitoring 16:11:57 INFO - Starting resource monitoring. 16:11:57 INFO - [mozharness: 2017-03-25 23:11:57.341404Z] Finished create-virtualenv step (success) 16:11:57 INFO - [mozharness: 2017-03-25 23:11:57.342393Z] Running install step. 16:11:57 INFO - Running pre-action listener: _resource_record_pre_action 16:11:57 INFO - Running main action method: install 16:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:57 INFO - Reading from file tmpfile_stdout 16:11:57 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:57 INFO - Reading from file tmpfile_stdout 16:11:57 INFO - Output received: 16:11:57 INFO - Usage: mozinstall [options] installer 16:11:57 INFO - Options: 16:11:57 INFO - -h, --help show this help message and exit 16:11:57 INFO - -d DEST, --destination=DEST 16:11:57 INFO - Directory to install application into. [default: 16:11:57 INFO - "/builds/slave/test"] 16:11:57 INFO - --app=APP Application being installed. [default: firefox] 16:11:57 INFO - mkdir: /builds/slave/test/build/application 16:11:57 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'] 16:11:57 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 16:12:18 INFO - Reading from file tmpfile_stdout 16:12:18 INFO - Output received: 16:12:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:12:18 INFO - Running post-action listener: _resource_record_post_action 16:12:18 INFO - [mozharness: 2017-03-25 23:12:18.253413Z] Finished install step (success) 16:12:18 INFO - [mozharness: 2017-03-25 23:12:18.253509Z] Running run-tests step. 16:12:18 INFO - Running pre-action listener: _resource_record_pre_action 16:12:18 INFO - Running main action method: run_tests 16:12:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:18 INFO - 2017-03-25 16:12:18.268 screenresolution[1635:9316] starting screenresolution argv=screenresolution get 16:12:18 INFO - 2017-03-25 16:12:18.270 screenresolution[1635:9316] Display 0: 1600x1200x32@60 16:12:18 INFO - 2017-03-25 16:12:18.276 screenresolution[1636:9318] starting screenresolution argv=screenresolution list 16:12:18 INFO - Available Modes on Display 0 16:12:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:18 INFO - Intel Iris: 16:12:18 INFO - Chipset Model: Intel Iris 16:12:18 INFO - Type: GPU 16:12:18 INFO - Bus: Built-In 16:12:18 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:18 INFO - Vendor: Intel (0x8086) 16:12:18 INFO - Device ID: 0x0a2e 16:12:18 INFO - Revision ID: 0x0009 16:12:18 INFO - Displays: 16:12:18 INFO - Display: 16:12:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:18 INFO - Display Serial Number: 1600x1200 60 16:12:18 INFO - Main Display: Yes 16:12:18 INFO - Mirror: Off 16:12:18 INFO - Online: Yes 16:12:18 INFO - Rotation: Supported 16:12:18 INFO - Return code: 0 16:12:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:18 INFO - Python 2.7.3 16:12:18 INFO - Return code: 0 16:12:18 INFO - grabbing minidump binary from tooltool 16:12:18 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')]} 16:12:18 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 0x1024b1d30>, '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 0x1023d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024a6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:18 INFO - Return code: 0 16:12:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:12:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:18 INFO - ENV: RUST_BACKTRACE is now 1 16:12:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:12:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cjFHichNm2/Render', 16:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:18 INFO - 'HOME': '/Users/cltbld', 16:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:18 INFO - 'LOGNAME': 'cltbld', 16:12:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:18 INFO - 'MOZ_NO_REMOTE': '1', 16:12:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:18 INFO - 'NO_EM_RESTART': '1', 16:12:18 INFO - 'PAGER': '/bin/cat', 16:12:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:18 INFO - 'PWD': '/builds/slave/test', 16:12:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:18 INFO - 'RUST_BACKTRACE': '1', 16:12:18 INFO - 'SHELL': '/bin/bash', 16:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q7v7wXAi4x/Listeners', 16:12:18 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 16:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:18 INFO - 'USER': 'cltbld', 16:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:18 INFO - 'XPC_FLAGS': '0x0', 16:12:18 INFO - 'XPC_SERVICE_NAME': '0', 16:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:12:18 INFO - mozversion application_buildid: 20170325153315 16:12:18 INFO - mozversion application_changeset: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee 16:12:18 INFO - mozversion application_display_name: Nightly 16:12:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:18 INFO - mozversion application_name: Firefox 16:12:18 INFO - mozversion application_remotingname: firefox 16:12:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:12:18 INFO - mozversion application_vendor: Mozilla 16:12:18 INFO - mozversion application_version: 55.0a1 16:12:18 INFO - mozversion platform_buildid: 20170325153315 16:12:18 INFO - mozversion platform_changeset: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee 16:12:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:12:18 INFO - mozversion platform_version: 55.0a1 16:12:18 INFO - using testdate: 1490483538 16:12:18 INFO - actual date: 1490483538 16:12:18 INFO - starting webserver on 'localhost:49240' 16:12:18 INFO - SUITE-START | Running 2 tests 16:12:18 INFO - TEST-START | dromaeo_css 16:12:18 INFO - operating with platform_type : mac_ 16:12:18 INFO - Initialising browser for dromaeo_css test... 16:12:18 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpQPpd6J/profile http://localhost:49240/getInfo.html) 16:12:21 INFO - PID 1646 | 2017-03-25 16:12:21.905 plugin-container[1648:9498] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 16:12:21 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:12:21 INFO - PID 1646 | 2017-03-25 16:12:21.908 plugin-container[1648:9498] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x941b, name = 'com.apple.CFPasteboardClient' 16:12:21 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:12:21 INFO - PID 1646 | 2017-03-25 16:12:21.908 plugin-container[1648:9498] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:12:21 INFO - PID 1646 | __metrics Screen width/height:1600/1200 16:12:21 INFO - PID 1646 | colorDepth:24 16:12:21 INFO - PID 1646 | Browser inner width/height: 1024/689 16:12:21 INFO - PID 1646 | __metrics 16:12:22 ERROR - PID 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 16:12:22 INFO - TEST-INFO | 1646: exit 0 16:12:22 INFO - Browser initialized. 16:12:22 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:22 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpQPpd6J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:25 INFO - PID 1650 | 2017-03-25 16:12:25.670 plugin-container[1651:9631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 16:12:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:12:25 INFO - PID 1650 | 2017-03-25 16:12:25.671 plugin-container[1651:9631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:12:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:12:25 INFO - PID 1650 | 2017-03-25 16:12:25.671 plugin-container[1651:9631] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:12:35 INFO - PID 1650 | RSS: Main: 227536896 16:12:35 INFO - PID 1650 | 16:15:39 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 16:15:40 INFO - PID 1650 | RSS: Main: 250236928 16:15:40 INFO - PID 1650 | 16:17:23 INFO - PID 1650 | ************************* 16:17:23 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 16:17:23 INFO - PID 1650 | 16:17:23 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:17:23 INFO - PID 1650 | Full stack: 16:17:23 INFO - PID 1650 | ************************* 16:18:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 16:18:43 INFO - PID 1650 | RSS: Main: 258826240 16:18:43 INFO - PID 1650 | 16:21:45 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 16:21:46 INFO - PID 1650 | RSS: Main: 270626816 16:21:46 INFO - PID 1650 | 16:24:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 16:24:49 INFO - PID 1650 | RSS: Main: 263004160 16:24:49 INFO - PID 1650 | 16:27:51 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 16:27:52 INFO - PID 1650 | RSS: Main: 273174528 16:27:52 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 16:30:55 INFO - PID 1650 | RSS: Main: 266981376 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | __start_tp_report 16:30:55 INFO - PID 1650 | _x_x_mozilla_page_load 16:30:55 INFO - PID 1650 | _x_x_mozilla_page_load_details 16:30:55 INFO - PID 1650 | |i|pagename|runs| 16:30:55 INFO - PID 1650 | |0;dojo.html;3117.7661675374347;3572.214112895164;3589.8205089745506;3613.132848116452;3621.203335266242;6113.32753397126;6182.041783523556;6186.381361863817;6215.440610345068;6240.84397890053;6126.884121540787;6142.437386315937;6159.846003849906;6222.9688851555675;6231.719572619231;5613;5643.8589035274035;5748.971255143719;5763.221965034709;5819.476247137757;5612.775488980456;5694.857628559278;5796.217510636074;5817.970910145444;5836.270466191726;5587.38538760736;5683.204351390808;5733.426657334275;5749.425057494259;5804.912926306109;5629.071203251499;5663.603547751659;5690.089585666315;5692.203091567184;5756.740946657411;5565.638233514808;5595.804146854841;5612.214289999403;5618.494335509783;5665.490105890449;5980.073088671263;6118.497354391992;6186.195794546725;6218.937810621881;6251.156093927352;6478.4169424752;6616.636085015322;6664.133662623828;6668.766593169215;6694.397504224595;6803.727850885959;6837.042814006043;6843.589384636938;6849.897251541231;6861.416779573742;6238.875222495525;6301.96849015752;6320.367963203643;6331.145295385156;6341.651209183447;5740.655560666373;6742.292059333798;6769.627670478072;6844.041834143224;6872.140982377203;5155.61332900034;5385.353757549119;6345.904811427832;6428.710708018057;6610.305917878553;5506.174073888868;5606.299212598426;6639.750103047714;6697.6986035628515;6749.156355455568;6673.299303573152;6678.732850686015;6697.966510167418;6698.263191048981;6699.296573859772;6411.358864113645;6514.3811337922825;6625.469962402996;6631.10480085192;6700.865982680417;5377.811776587879;5385.973070134624;5386.380566234933;5397.217403476474;5417.160340147283;4793.281007848781;4800.975995120072;4809.2305231162845;4815;4835.274708793639;6191.318954914956;6208.906866397007;6255.437010669061;6270.2475702915945;6296.874062518724;6759.5606285591275;6764.72941082361;6830.829229269209;6839.521233513706;6849.828754281083;6236.750529978841;6302.464290535264;6302.4958003359625;6304.275008374004;6322.652254126067;5818.7672493100645;5857.4435428634215;6051.183090282843;6067.787627433018;6079.574429789872;6727.93272067273;6820.181578210647;6875;6879.89680154788;6899.931000689929;6762.087118238975;6779.6271205084195;6780.69486873082;6793.796186114425;6801.557898736566;6858.725650974105;6867;6869.519133660796;6880.415164711056;6910.7581234656545;5471.234027236111;6387.457066149429;6471.029345598198;6617.66911654425;6642.468602511691;6770.6614669265355;6774.627395493098;6775.085363476065;6780.118584583973;6788.59268443895;6220;6314.958031924681;6464.644444555594;6514.609123452608;6530.934690653223;6548.0832683423405;6561.96719016421;6580.440662543738;6592.967035164986;6598.901016484852;5210.6613070150315;6221.96889015552;6286.308506064421;6292.2134733158355;6340.4293613575;6496.350364963655;6580.703868325841;6587.242467116343;6596.208454985433;6601.042848786995;6401.039844023434;6409.166808314889;6409.327020662763;6412.294647588668;6450.548461607643 16:30:55 INFO - PID 1650 | |1;ext.html;694.9861002779943;775.891375207471;1208.667616405488;1220.3410158514403;1242.0374209987258;39400.60599394005;40085.79957100218;40251.79874100629;40278.79860600697;40316;38947;39410.8029459853;39453;39651;39663.80168099163;8179.46833455825;8629.093945135764;8810.691625793099;8853;8863.556822158898;8691.65279381697;8774.692885749;8865.024847266795;8869.600867960926;8905.243054340388;8936.8659470108;9002.549872506348;9013.278937684969;9024.684136055239;9037.502937338444;8948.64205431782;8961.05908879568;8975.461472311683;8976.326775491863;8985.23625491834;8635.52504612246;8662.740117796477;8693.652253909837;8699.826003479895;8718.476891386537;38822.41766373507;38836.611633883585;39005.82982510501;39072;39147.804260978795;2988.835614041227;2992.895248666308;3003.714647108521;3003.8498075096377;3007.879684812605;28108.71891281102;28222.012229572072;28233.717662823314;28567.714322856922;28571;2800.8179468334492;2823.1953893140562;2824.703406142346;2827.4627820714204;2856.657201135856;354896;359715;360295;360681;361523;40176;40463.595364046574;47720.522794771605;48385.032299353814;48877;43206.56793432026;47943;50437;50437.74781126144;50592.49407505951;14420.639484012983;14447.927760361341;14535.709285814224;14627.634309142357;14680.119192848464;11628.709282267842;11636.476358563885;11700.35648039366;11704.180707350404;11717.00405465528;13785.035047546777;13786.448542058406;13790.448382064806;13843;13915.512957046454;6081;6087.786927457518;6088.360722124202;6097.481714054266;6113.510919126459;965.3098034905076;972.8783902012107;980.1227901028682;986.1026465915982;1012.9088382045651;22069.558608828054;24394.146204883098;24551;24555.754442455705;25396;1020.5866624017285;1045.430240518919;1079.0989523747758;1088.4775307852276;1095.1950316517352;10317.638882639072;12819.807702884464;12827.743445131231;12878.09853310278;12987.155834870704;406762;475782;478208;480058.59970700624;480467;45144.54855451403;56070.15894761582;56149.43850561524;56186;56278.59303517445;1472.9189894555689;1493.1787516865681;1536.9462068827538;1540.4839378808;1544.691061787647;24107.397315067265;24244.51510969806;24281.878590606935;24331.63502547428;24393.756062439148;40471;40658.39012414757;40816.97957551085;40884.79557602193;40903;462498;465209;468818;469009;469341;26074;26408.8679556601;29083.982060627775;30533.389332212788;33934.490982635776;33568.3286334267;38076;38838.6116138835;39371.80314098411;39717.80141099276;27683.861580692774;27781.027664031662;28063;28489.43021139524;28804;28060.43879122447;28064;28273.43453130967;28555.143345699664;28650.14049578516;25424.872875635505;33478;34463.82768086143;34505.96482105541;34640.78757243585;28758.281042974093;29085.563716543846;29795.55306670445;30046.54930176093;30310.242243944078;22713.545729085658;23488.060477580704;23752.812359381616;23960.40098997539;24048.759512404653 16:30:55 INFO - PID 1650 | |2;jquery.html;364.220567984513;381.46785234597735;423.59123445874746;436.6135969666845;446.09442831052974;20348.796512034893;20917.372478825622;21257.893710531425;21330.36008919733;21354.893225533888;22014.44963875904;22067.558648827013;22253;22817.54364912701;22818.20136295226;6082;6328.1773369462135;6355.9364406356035;6370.267419246799;6370.649614271213;6017.097435384683;6164.7534098636;6185.072239164117;6247.969085100953;6251.499880009611;5710.14347847822;5886.793962211324;6167.414095660905;6206.379362063803;6216.129741836146;5939.346671866091;6114.938850611481;6178.536609754242;6208.689565521706;6222.0666899965145;5344.305240318734;5429.375621803503;5534.723263836792;5534.944650553523;5545.195946587763;27084.59373109405;27254.45491090187;27339.725493527014;27425.86287068572;27479.725202748115;1792.5339411752907;2068.524239424926;2082.0422605601443;2085.113826623679;2138.155428605692;15227.923860380737;15773.763393549107;16447.506574802774;16692.332306707718;17004.659906801793;1736.2794440307257;1739.625980414217;1742.9799398493235;1748.2133040131864;1769.1172364274566;163982;173231;174700.99999999872;175105;175893;10799.406032668121;12927.870721292667;13029.915213565973;13186.802197966847;13253.271070091238;16784.328626855033;16790.74813877793;16801.24394402255;16848.494545163663;16876.73424493168;7158.319959603829;7887.250711182429;9187.540622968825;9191.264698824078;9245.676401325922;7305.598192099379;7321.450891183183;8624.827503449897;8881.866771998426;8888.066752990842;6454.677266136674;8062.516249025077;8093.109757926623;8098.919010809935;8120.837583248302;2117.25895936421;2134.893255337227;2135.775743546936;2142.742870855507;2334.323046316549;813.2954392741121;908.8091500784909;915.190056799737;935.1677007463363;962.6967505235829;20676.379708608765;21202.57594848125;21222.681659775117;21254.043568039477;21735.23926662559;929.4051806843626;956.7703751099825;960.9333639659972;971.7910649210454;985.1675334342469;9789.461579613047;11019.559217631366;11043;11074.501647425885;11126.833097503382;698661;703782;705190.4740476265;719499;728124;155960;161892;163903;166840.3315966849;169154;1143.8627364716288;1167.1246565076192;1190.6249531397582;1199.4151122352328;1222.6270987348944;21114.94425278699;21685.241016564345;21939.780602193772;22023.66964495566;22114.447138821015;62758.68620656867;63777.681111594145;64044.398890028126;64834.35165648283;65482.34517654763;89193.55403222943;89320;91559;91741;91779;8681.305495560466;8860.601272942604;8906.510141941997;8947.731568052968;9193.034731353378;13780.724385512434;13857.099288546213;13879.514217002357;14248.501302454364;14394.928025359806;9647.517624118906;11750.118741094622;11789.823152652889;11898;11910.225835320676;9460.668876589287;9985.201183905416;11330.37682927447;11352.432378381212;11461.140414468782;11691;11800.409979500819;12087.637370878887;12134.393280335771;12207.389630518615;8680;8680.348973826862;8873.334499912404;8923;9092.136247056518;11517.25137866036;11656.825147622962;11857.051435885282;11968.521259149536;12057.035437165181 16:30:55 INFO - PID 1650 | |3;mootools.html;1168.4975460551962;1432.090622454741;1436.9869242184254;1446.3202294921396;1452.353702602342;10577.894221057788;10959.83560246597;11009.449527523631;11021;11172.994430501254;11058.336499810017;11066.833997490025;11172.776544469105;11230.270032447885;11240.718982025453;2431.3435372449426;2499.5875680512695;2504.949901001979;2510.535550923076;2511;2401.903923843044;2446.2783478873685;2474.2206205045354;2474.6782918220606;2497.837640553358;2201.9559608807813;2353.305774796439;2397.2688330059327;2433.8904749286235;2437.549053425122;2391.6771235883107;2455.484348286862;2464.445499762548;2469.6789417375717;2481.255623312999;1968.4291555448904;1980.6137803128395;2484.6148846928754;2485.5898776701824;2624.7768939640155;10952.726181845517;11060.778784424267;11085;11213.439328033566;11219.38293393863;6233.719482623293;6454.838629034265;6463.709133088975;6480.4815614750705;6483.22201335838;3843.7885916274595;3851.1912498375373;3899.1811719538646;3935.976646072042;4033.233685599727;1171.2152857585354;1181.1967861853936;1188.4176753390864;1206.4993027893413;1224.7856625090662;11722.062235021178;14260.999999999896;15389.692206155925;15522.611934701723;15532.145731984736;5350.705711185922;5541.944580554143;5580.386157522669;5583.134614134816;5646.774129034792;10902.236843420884;11456.656300311004;11518.36648984314;11590.13074019452;11618.825717614243;6136.9079463808075;6322.841928951813;6342.429181373606;6349.587276826992;6354.396332348512;5915.1718759373725;5965.13505541694;5980.820575382745;6047.485963693135;6057.757689692363;4266.573342665709;4298.978505107454;4378.737275763465;4382.53983331752;4443.244648409778;1107.2304748200086;1281.6923938254752;1287.0797379873436;1299.7140629061591;1310.606817954629;641.6246495800009;762.7902326860158;791.2838880812874;794.1026639896995;826.8428998490267;6227.408396202353;6592.30780768022;6682.63245521501;6734.292899245509;6735.595864248161;877.2981614708208;901.9458832470073;902.8329758994607;904.2991681446931;935.8783358163395;4065;4125.855595054159;4199.139176468878;4202.978985105116;4230.936535951963;13743.175409475463;14176.362063707158;14249.928750356183;14259.857401426061;14276.286185690882;13883.514077007458;14099.295035248197;14159.433622655184;14169.22069286199;14200.573982780534;9779.51102444875;10214.64248751305;10230.795384092276;10238.641647542301;10243.38539687621;6472.838179045561;6615.272320044695;6660.900086498803;6890.689918953559;6999.755008574675;12377.51469823627;12454.25274483534;12518.937405312916;12646.683832904251;12712.491500340253;10969.945150274198;11819.704507387383;12078.27530348182;12724.491020359083;12909.483620655068;8843.248323892542;9234.769130771785;9242.260619150586;9255.861162082709;9269.397489163182;10030.548625312025;10137.746556335856;10173.949130254301;10252.38485690861;10294.742631433974;8537.09117257567;8734.30125589964;8821.955890220508;8852;8929.910700892908;8455.74632761018;8493.32053435736;8517;8563.486190828571;8592.269657079218;7776.688932442639;7818.882716759367;7857.253560911704;7924.4056695746895;7930.206979302024;5633.633813802254;5639.54883609302;5820.534357251495;5905.35041145465;6014.338422773404;5194.3766747990485;5889.882202355843;5954.672493012926;5983.581149319506;6035.78874739382 16:30:55 INFO - PID 1650 | |4;prototype.html;321.3075821604443;329.8169515918664;356.38167778903596;390.39293897988654;412.9194807012632;6161.969190154055;6214.223222097238;6255.244336116994;6303.3066362700065;6339.654327898684;5293.947060529394;6072.939270607292;6191.4737247333915;6243.56295059346;6263.592866463688;3635.7273204509634;4545.977155140084;4705.3412522247;4712.175369310374;4729.787159577827;3991.580884007181;4661.743604101773;4692.882677933062;4697.13103075932;4742.834000809972;4689.437267527901;4695.3191787190835;4713.5286471352765;4715.221988371914;4715.976420117911;3557.3418917500344;3925.450436938831;4523.932141017887;4681.368015317923;4722.763861806896;3284.868605255787;3983.9641693159697;4001.7598944063157;4019.356902895552;4022.5374081980654;5441.646292990943;5931.495822855062;6143.416375444371;6164.044573091178;6179.289381721114;5297.761600727977;5440.4287549807495;5468.152436372368;5470.589705772083;5495.148252020903;6668.366505182;6671.5997040177745;6686.93313066868;6693.665316734193;6709.429698475636;3682.4844521766972;3748.8565987374113;4485.798139083749;4485.977570112129;4507.774611269423;5469.456702633294;6715.597064176165;6900.516963812485;7122.5726456412785;7197.316254955771;3767;4401.977990110029;4522.773861306987;4527.822766080777;4538.636909047268;4594.839180628729;4612.492625811158;4619.5611416915335;4628.328892310664;4640.95578494836;5306.336707911511;5368.275282836845;5412.702301373383;5467.152591348347;5467.4532546745;6342.904856427157;6726.192856857208;6726.730930762715;6736.6631668416385;6746.22418421878;1373.8557451467652;1521.767368431574;1574.2994367506578;1577.7948866647262;1588.602849287678;909.8672153169214;1011.676263595657;1037.387941114746;1039.6673064619401;1042.6819819954835;5304;6177.660228687376;6281.842953926189;6295.181626388632;6323.588966717149;5130.820421285237;6020.668863212565;6728.562643428161;6864.24493305736;6983;6229.065640153923;6233.532485063548;6274;6312.4634406076;6328.77875752506;3619.077135330474;3627.6916462101017;3633.4913112164318;3656.2321912398693;3672.926541469156;6136.527233463937;6791.4906382021545;7096.680649370791;7161.928380716126;7164.892526612;5501.724913754297;5841.88316233673;6206;7204.279572042754;7213.242609526029;6937.965310173417;7103.964480177669;7130.821729456701;7155.642217888981;7163.89254161188;6052.60790018307;6070.362611925684;6943.6875340608785;6968.930310696828;7007.229204787572;6130.601510901777;6558.573692709959;6624.8012559623285;6865.828354290983;6896.7586134485055;6779.15260592426;6912.585244885323;6967.163940327193;7058.505904586835;7134.857302854018;4526.751028693454;4527.615152712056;4528.343390208335;4549.795259213277;4559.065391594649;6217.4715149212825;6563.803085907431;6663.26704062543;6887.586744795329;6992.965035174791;6779.796606101825;6802.3537763912345;6809.489288303298;6827.249002609609;6828.863422731617;5839.328477225174;6583.769568065286;6761.22245941723;6770.763023294161;6776.390124888783;6642.568233064835;6648.8139251384;6661.433778128913;6667.599944003376;6673.265940746417;6539.378759017885;6798.456123510206;6809.591424514545;6822.795316140524;6879.243283238739;6523.966355644648;6542;6610;6616.8420526408645;6628.5691430056895 16:30:55 INFO - PID 1650 | |5;yui.html;685.7428464325877;724.9130104387473;726.7928640337504;728.7048745258172;731.952423092499;12845.678858028554;14977.47578834741;15551.300191491382;15608.453704120358;15650.53048408548;14348.42606295747;14496.782548261757;14498.42006319746;14515.854841451583;15022.399104035825;913.4884464699757;918.9540522973858;942.8067246214544;953.8521529162956;960.577345967772;956.4548207521715;967.8016006718606;968.9370190937602;970.7136394763562;976.6288810252095;915.1397686175007;957.6839642917855;998.3560603410796;999.7200783780553;1014.345746993189;958.7219706285169;1002.6791426743408;1006.6174853555602;1008.8486726990936;1011.4285428732743;658.1641315529251;745.944054195932;750.6847124207845;751.2374939436477;752.5259086775351;16009.759853602205;16133.515994520183;16313.265903034391;16399.344026239054;16659.583510412216;14542.345594448276;14547.70904581913;14619.365990800798;14748.005846523758;14757.631059223499;11499.942500287529;11613.825792613117;11786.705332366673;12065;12223.69440763979;2907.898223562165;2932.736053755172;2952.5866378706996;3019.864106115208;3021.305099827052;87390.56304718499;96113.51943240239;108111.01431238333;108392.45803770931;110162.9999999992;10425.582976681;11666.76666466683;11716.602185366319;11842.763144737057;12063.914247717748;22074.558508830054;22121.33635990923;24756;25035;25384.619230711553;6502.0246962955935;6591;7311.451641126937;7602.353799926957;7788.299053085249;4042.292598795199;4123.814428350672;4130.359794231899;4131.97934010328;4132.8140233689555;3896.6103389661375;3950.7234493585174;3994.6005399460405;3994.6205110514456;3998.620131087542;782.4796510320687;790.5928446849877;804.4931693033468;807.4993504027541;807.5356669914822;544.5942772634413;559.7621011070279;579.4408395897979;582.5543459253674;597.333972620525;8913.55432228399;8991.730248092568;8999.775005624912;9868;9899.65351212704;540.187018537107;578.2800413485154;589.2899056636743;598.1715324275909;607.3501353551658;4535.614472769851;4652.744099074515;4703.223968045234;4715.528447155326;4795.886398559808;95142.1558089649;98989;99697;101620.98379016115;101980;71151;89256;89429;90961;91369;71488.21279468056;72069;73006;73792.63103684447;74637.88043179357;8646.524441155796;9027.277817774693;9322.161005509537;9335.346525743133;9372.859407108763;31446.899358522343;36556;38428.42357364586;39134.99999999886;40036.59963400445;44309.77845110754;47749.76125119352;51190.74404628102;52054.479455204964;52128.43614691565;9492.335536512377;11134.610288639858;11540.365279909685;11584.652460426201;11617.419129043683;12308.630741077783;12345.753084938431;12361.443735031766;12406;12591.685207869876;11473.311601303949;11529.884701152881;11592.420378981185;11748.941255293668;11956.641300760992;11540.596079137189;11648.41757912084;11796.823047654463;11797.528098876292;12109.576164834189;11160;11245.437728113726;11367.54529818831;11731.178817483036;11738.119641027128;6445.452136568444;7472.476926615303;8499.405041647025;8564.314854811724;8650.610722517627;7499.437542184249;8787.824243515222;9090.909090909006;9148.542572871464;9637.9036209637 16:30:55 INFO - PID 1650 | __end_tp_report 16:30:55 INFO - PID 1650 | __start_cc_report 16:30:55 INFO - PID 1650 | _x_x_mozilla_cycle_collect,420 16:30:55 INFO - PID 1650 | __end_cc_report 16:30:55 INFO - PID 1650 | __startTimestamp1490484655809__endTimestamp 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | ------- Summary: start ------- 16:30:55 INFO - PID 1650 | Number of tests: 6 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6167.27 Median:6314.96 stddev:698.89 (11.1%) stddev-sans-first:658.82 16:30:55 INFO - PID 1650 | Values: 3117.8 3572.2 3589.8 3613.1 3621.2 6113.3 6182.0 6186.4 6215.4 6240.8 6126.9 6142.4 6159.8 6223.0 6231.7 5613.0 5643.9 5749.0 5763.2 5819.5 5612.8 5694.9 5796.2 5818.0 5836.3 5587.4 5683.2 5733.4 5749.4 5804.9 5629.1 5663.6 5690.1 5692.2 5756.7 5565.6 5595.8 5612.2 5618.5 5665.5 5980.1 6118.5 6186.2 6218.9 6251.2 6478.4 6616.6 6664.1 6668.8 6694.4 6803.7 6837.0 6843.6 6849.9 6861.4 6238.9 6302.0 6320.4 6331.1 6341.7 5740.7 6742.3 6769.6 6844.0 6872.1 5155.6 5385.4 6345.9 6428.7 6610.3 5506.2 5606.3 6639.8 6697.7 6749.2 6673.3 6678.7 6698.0 6698.3 6699.3 6411.4 6514.4 6625.5 6631.1 6700.9 5377.8 5386.0 5386.4 5397.2 5417.2 4793.3 4801.0 4809.2 4815.0 4835.3 6191.3 6208.9 6255.4 6270.2 6296.9 6759.6 6764.7 6830.8 6839.5 6849.8 6236.8 6302.5 6302.5 6304.3 6322.7 5818.8 5857.4 6051.2 6067.8 6079.6 6727.9 6820.2 6875.0 6879.9 6899.9 6762.1 6779.6 6780.7 6793.8 6801.6 6858.7 6867.0 6869.5 6880.4 6910.8 5471.2 6387.5 6471.0 6617.7 6642.5 6770.7 6774.6 6775.1 6780.1 6788.6 6220.0 6315.0 6464.6 6514.6 6530.9 6548.1 6562.0 6580.4 6593.0 6598.9 5210.7 6222.0 6286.3 6292.2 6340.4 6496.4 6580.7 6587.2 6596.2 6601.0 6401.0 6409.2 6409.3 6412.3 6450.5 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:55462.66 Median:357305.50 stddev:115320.03 (32.3%) stddev-sans-first:115570.22 16:30:55 INFO - PID 1650 | Values: 695.0 775.9 1208.7 1220.3 1242.0 39400.6 40085.8 40251.8 40278.8 40316.0 38947.0 39410.8 39453.0 39651.0 39663.8 8179.5 8629.1 8810.7 8853.0 8863.6 8691.7 8774.7 8865.0 8869.6 8905.2 8936.9 9002.5 9013.3 9024.7 9037.5 8948.6 8961.1 8975.5 8976.3 8985.2 8635.5 8662.7 8693.7 8699.8 8718.5 38822.4 38836.6 39005.8 39072.0 39147.8 2988.8 2992.9 3003.7 3003.8 3007.9 28108.7 28222.0 28233.7 28567.7 28571.0 2800.8 2823.2 2824.7 2827.5 2856.7 354896.0 359715.0 360295.0 360681.0 361523.0 40176.0 40463.6 47720.5 48385.0 48877.0 43206.6 47943.0 50437.0 50437.7 50592.5 14420.6 14447.9 14535.7 14627.6 14680.1 11628.7 11636.5 11700.4 11704.2 11717.0 13785.0 13786.4 13790.4 13843.0 13915.5 6081.0 6087.8 6088.4 6097.5 6113.5 965.3 972.9 980.1 986.1 1012.9 22069.6 24394.1 24551.0 24555.8 25396.0 1020.6 1045.4 1079.1 1088.5 1095.2 10317.6 12819.8 12827.7 12878.1 12987.2 406762.0 475782.0 478208.0 480058.6 480467.0 45144.5 56070.2 56149.4 56186.0 56278.6 1472.9 1493.2 1536.9 1540.5 1544.7 24107.4 24244.5 24281.9 24331.6 24393.8 40471.0 40658.4 40817.0 40884.8 40903.0 462498.0 465209.0 468818.0 469009.0 469341.0 26074.0 26408.9 29084.0 30533.4 33934.5 33568.3 38076.0 38838.6 39371.8 39717.8 27683.9 27781.0 28063.0 28489.4 28804.0 28060.4 28064.0 28273.4 28555.1 28650.1 25424.9 33478.0 34463.8 34506.0 34640.8 28758.3 29085.6 29795.6 30046.5 30310.2 22713.5 23488.1 23752.8 23960.4 24048.8 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:42057.88 Median:14709.46 stddev:120019.00 (815.9%) stddev-sans-first:120314.85 16:30:55 INFO - PID 1650 | Values: 364.2 381.5 423.6 436.6 446.1 20348.8 20917.4 21257.9 21330.4 21354.9 22014.4 22067.6 22253.0 22817.5 22818.2 6082.0 6328.2 6355.9 6370.3 6370.6 6017.1 6164.8 6185.1 6248.0 6251.5 5710.1 5886.8 6167.4 6206.4 6216.1 5939.3 6114.9 6178.5 6208.7 6222.1 5344.3 5429.4 5534.7 5534.9 5545.2 27084.6 27254.5 27339.7 27425.9 27479.7 1792.5 2068.5 2082.0 2085.1 2138.2 15227.9 15773.8 16447.5 16692.3 17004.7 1736.3 1739.6 1743.0 1748.2 1769.1 163982.0 173231.0 174701.0 175105.0 175893.0 10799.4 12927.9 13029.9 13186.8 13253.3 16784.3 16790.7 16801.2 16848.5 16876.7 7158.3 7887.3 9187.5 9191.3 9245.7 7305.6 7321.5 8624.8 8881.9 8888.1 6454.7 8062.5 8093.1 8098.9 8120.8 2117.3 2134.9 2135.8 2142.7 2334.3 813.3 908.8 915.2 935.2 962.7 20676.4 21202.6 21222.7 21254.0 21735.2 929.4 956.8 960.9 971.8 985.2 9789.5 11019.6 11043.0 11074.5 11126.8 698661.0 703782.0 705190.5 719499.0 728124.0 155960.0 161892.0 163903.0 166840.3 169154.0 1143.9 1167.1 1190.6 1199.4 1222.6 21114.9 21685.2 21939.8 22023.7 22114.4 62758.7 63777.7 64044.4 64834.4 65482.3 89193.6 89320.0 91559.0 91741.0 91779.0 8681.3 8860.6 8906.5 8947.7 9193.0 13780.7 13857.1 13879.5 14248.5 14394.9 9647.5 11750.1 11789.8 11898.0 11910.2 9460.7 9985.2 11330.4 11352.4 11461.1 11691.0 11800.4 12087.6 12134.4 12207.4 8680.0 8680.3 8873.3 8923.0 9092.1 11517.3 11656.8 11857.1 11968.5 12057.0 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:6829.33 Median:2507.74 stddev:4186.57 (166.9%) stddev-sans-first:4176.70 16:30:55 INFO - PID 1650 | Values: 1168.5 1432.1 1437.0 1446.3 1452.4 10577.9 10959.8 11009.4 11021.0 11173.0 11058.3 11066.8 11172.8 11230.3 11240.7 2431.3 2499.6 2504.9 2510.5 2511.0 2401.9 2446.3 2474.2 2474.7 2497.8 2202.0 2353.3 2397.3 2433.9 2437.5 2391.7 2455.5 2464.4 2469.7 2481.3 1968.4 1980.6 2484.6 2485.6 2624.8 10952.7 11060.8 11085.0 11213.4 11219.4 6233.7 6454.8 6463.7 6480.5 6483.2 3843.8 3851.2 3899.2 3936.0 4033.2 1171.2 1181.2 1188.4 1206.5 1224.8 11722.1 14261.0 15389.7 15522.6 15532.1 5350.7 5541.9 5580.4 5583.1 5646.8 10902.2 11456.7 11518.4 11590.1 11618.8 6136.9 6322.8 6342.4 6349.6 6354.4 5915.2 5965.1 5980.8 6047.5 6057.8 4266.6 4299.0 4378.7 4382.5 4443.2 1107.2 1281.7 1287.1 1299.7 1310.6 641.6 762.8 791.3 794.1 826.8 6227.4 6592.3 6682.6 6734.3 6735.6 877.3 901.9 902.8 904.3 935.9 4065.0 4125.9 4199.1 4203.0 4230.9 13743.2 14176.4 14249.9 14259.9 14276.3 13883.5 14099.3 14159.4 14169.2 14200.6 9779.5 10214.6 10230.8 10238.6 10243.4 6472.8 6615.3 6660.9 6890.7 6999.8 12377.5 12454.3 12518.9 12646.7 12712.5 10969.9 11819.7 12078.3 12724.5 12909.5 8843.2 9234.8 9242.3 9255.9 9269.4 10030.5 10137.7 10173.9 10252.4 10294.7 8537.1 8734.3 8822.0 8852.0 8929.9 8455.7 8493.3 8517.0 8563.5 8592.3 7776.7 7818.9 7857.3 7924.4 7930.2 5633.6 5639.5 5820.5 5905.4 6014.3 5194.4 5889.9 5954.7 5983.6 6035.8 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5402.61 Median:6163.01 stddev:1714.52 (27.8%) stddev-sans-first:1676.38 16:30:55 INFO - PID 1650 | Values: 321.3 329.8 356.4 390.4 412.9 6162.0 6214.2 6255.2 6303.3 6339.7 5293.9 6072.9 6191.5 6243.6 6263.6 3635.7 4546.0 4705.3 4712.2 4729.8 3991.6 4661.7 4692.9 4697.1 4742.8 4689.4 4695.3 4713.5 4715.2 4716.0 3557.3 3925.5 4523.9 4681.4 4722.8 3284.9 3984.0 4001.8 4019.4 4022.5 5441.6 5931.5 6143.4 6164.0 6179.3 5297.8 5440.4 5468.2 5470.6 5495.1 6668.4 6671.6 6686.9 6693.7 6709.4 3682.5 3748.9 4485.8 4486.0 4507.8 5469.5 6715.6 6900.5 7122.6 7197.3 3767.0 4402.0 4522.8 4527.8 4538.6 4594.8 4612.5 4619.6 4628.3 4641.0 5306.3 5368.3 5412.7 5467.2 5467.5 6342.9 6726.2 6726.7 6736.7 6746.2 1373.9 1521.8 1574.3 1577.8 1588.6 909.9 1011.7 1037.4 1039.7 1042.7 5304.0 6177.7 6281.8 6295.2 6323.6 5130.8 6020.7 6728.6 6864.2 6983.0 6229.1 6233.5 6274.0 6312.5 6328.8 3619.1 3627.7 3633.5 3656.2 3672.9 6136.5 6791.5 7096.7 7161.9 7164.9 5501.7 5841.9 6206.0 7204.3 7213.2 6938.0 7104.0 7130.8 7155.6 7163.9 6052.6 6070.4 6943.7 6968.9 7007.2 6130.6 6558.6 6624.8 6865.8 6896.8 6779.2 6912.6 6967.2 7058.5 7134.9 4526.8 4527.6 4528.3 4549.8 4559.1 6217.5 6563.8 6663.3 6887.6 6993.0 6779.8 6802.4 6809.5 6827.2 6828.9 5839.3 6583.8 6761.2 6770.8 6776.4 6642.6 6648.8 6661.4 6667.6 6673.3 6539.4 6798.5 6809.6 6822.8 6879.2 6524.0 6542.0 6610.0 6616.8 6628.6 16:30:55 INFO - PID 1650 | 16:30:55 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:18862.05 Median:4594.18 stddev:27548.89 (599.6%) stddev-sans-first:27592.37 16:30:55 INFO - PID 1650 | Values: 685.7 724.9 726.8 728.7 732.0 12845.7 14977.5 15551.3 15608.5 15650.5 14348.4 14496.8 14498.4 14515.9 15022.4 913.5 919.0 942.8 953.9 960.6 956.5 967.8 968.9 970.7 976.6 915.1 957.7 998.4 999.7 1014.3 958.7 1002.7 1006.6 1008.8 1011.4 658.2 745.9 750.7 751.2 752.5 16009.8 16133.5 16313.3 16399.3 16659.6 14542.3 14547.7 14619.4 14748.0 14757.6 11499.9 11613.8 11786.7 12065.0 12223.7 2907.9 2932.7 2952.6 3019.9 3021.3 87390.6 96113.5 108111.0 108392.5 110163.0 10425.6 11666.8 11716.6 11842.8 12063.9 22074.6 22121.3 24756.0 25035.0 25384.6 6502.0 6591.0 7311.5 7602.4 7788.3 4042.3 4123.8 4130.4 4132.0 4132.8 3896.6 3950.7 3994.6 3994.6 3998.6 782.5 790.6 804.5 807.5 807.5 544.6 559.8 579.4 582.6 597.3 8913.6 8991.7 8999.8 9868.0 9899.7 540.2 578.3 589.3 598.2 607.4 4535.6 4652.7 4703.2 4715.5 4795.9 95142.2 98989.0 99697.0 101621.0 101980.0 71151.0 89256.0 89429.0 90961.0 91369.0 71488.2 72069.0 73006.0 73792.6 74637.9 8646.5 9027.3 9322.2 9335.3 9372.9 31446.9 36556.0 38428.4 39135.0 40036.6 44309.8 47749.8 51190.7 52054.5 52128.4 9492.3 11134.6 11540.4 11584.7 11617.4 12308.6 12345.8 12361.4 12406.0 12591.7 11473.3 11529.9 11592.4 11748.9 11956.6 11540.6 11648.4 11796.8 11797.5 12109.6 11160.0 11245.4 11367.5 11731.2 11738.1 6445.5 7472.5 8499.4 8564.3 8650.6 7499.4 8787.8 9090.9 9148.5 9637.9 16:30:55 INFO - PID 1650 | -------- Summary: end -------- 16:30:55 INFO - PID 1650 | 16:30:56 INFO - TEST-INFO | 1650: exit 0 16:30:56 INFO - TEST-OK | dromaeo_css | took 1117639ms 16:30:56 INFO - TEST-START | kraken 16:30:56 INFO - operating with platform_type : mac_ 16:30:56 INFO - Initialising browser for kraken test... 16:30:56 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp2x7LUK/profile http://localhost:49240/getInfo.html) 16:30:59 INFO - PID 1660 | 2017-03-25 16:30:59.287 plugin-container[1661:12064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 16:30:59 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:59 INFO - PID 1660 | 2017-03-25 16:30:59.293 plugin-container[1661:12064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.CFPasteboardClient' 16:30:59 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:59 INFO - PID 1660 | 2017-03-25 16:30:59.293 plugin-container[1661:12064] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:59 INFO - PID 1660 | __metrics Screen width/height:1600/1200 16:30:59 INFO - PID 1660 | colorDepth:24 16:30:59 INFO - PID 1660 | Browser inner width/height: 1024/689 16:30:59 INFO - PID 1660 | __metrics 16:30:59 ERROR - PID 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 16:31:00 INFO - TEST-INFO | 1660: exit 0 16:31:00 INFO - Browser initialized. 16:31:00 INFO - Running cycle 1/1 for kraken test... 16:31:00 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp2x7LUK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:02 INFO - PID 1662 | 2017-03-25 16:31:02.576 plugin-container[1663:12188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 16:31:02 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:31:02 INFO - PID 1662 | 2017-03-25 16:31:02.577 plugin-container[1663:12188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.CFPasteboardClient' 16:31:02 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:31:02 INFO - PID 1662 | 2017-03-25 16:31:02.577 plugin-container[1663:12188] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:31:12 INFO - PID 1662 | RSS: Main: 227635200 16:31:12 INFO - PID 1662 | 16:31:14 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:15 INFO - PID 1662 | RSS: Main: 242024448 16:31:15 INFO - PID 1662 | 16:31:18 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 16:31:19 INFO - PID 1662 | RSS: Main: 250642432 16:31:19 INFO - PID 1662 | 16:31:22 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 16:31:23 INFO - PID 1662 | RSS: Main: 253272064 16:31:23 INFO - PID 1662 | 16:31:25 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 16:31:25 INFO - PID 1662 | RSS: Main: 255660032 16:31:25 INFO - PID 1662 | 16:31:27 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:28 INFO - PID 1662 | RSS: Main: 256212992 16:31:28 INFO - PID 1662 | 16:31:31 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:32 INFO - PID 1662 | RSS: Main: 261738496 16:31:32 INFO - PID 1662 | 16:31:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:36 INFO - PID 1662 | RSS: Main: 262430720 16:31:36 INFO - PID 1662 | 16:31:39 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 16:31:39 INFO - PID 1662 | RSS: Main: 262574080 16:31:39 INFO - PID 1662 | 16:31:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:42 INFO - PID 1662 | RSS: Main: 262365184 16:31:42 INFO - PID 1662 | 16:31:44 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:44 INFO - PID 1662 | RSS: Main: 262168576 16:31:44 INFO - PID 1662 | 16:31:47 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:47 INFO - PID 1662 | RSS: Main: 262270976 16:31:47 INFO - PID 1662 | 16:31:50 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:51 INFO - PID 1662 | RSS: Main: 262979584 16:31:51 INFO - PID 1662 | 16:31:54 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:55 INFO - PID 1662 | RSS: Main: 262586368 16:31:55 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 16:31:57 INFO - PID 1662 | RSS: Main: 264335360 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | __start_tp_report 16:31:57 INFO - PID 1662 | _x_x_mozilla_page_load 16:31:57 INFO - PID 1662 | _x_x_mozilla_page_load_details 16:31:57 INFO - PID 1662 | |i|pagename|runs| 16:31:57 INFO - PID 1662 | |0;ai-astar;104;121;117;118;118;119;117;117;116;118 16:31:57 INFO - PID 1662 | |1;audio-beat-detection;146;115;115;186;153;105;147;156;135;130 16:31:57 INFO - PID 1662 | |2;audio-dft;195;202;207;214;226;197;202;199;198;193 16:31:57 INFO - PID 1662 | |3;audio-fft;81;59;58;86;59;62;59;61;72;58 16:31:57 INFO - PID 1662 | |4;audio-oscillator;88;70;88;71;70;70;88;106;107;117 16:31:57 INFO - PID 1662 | |5;imaging-gaussian-blur;91;88;136;92;90;117;81;79;85;137 16:31:57 INFO - PID 1662 | |6;imaging-darkroom;99;148;154;122;98;103;97;99;99;145 16:31:57 INFO - PID 1662 | |7;imaging-desaturate;90;94;92;91;91;92;91;92;91;83 16:31:57 INFO - PID 1662 | |8;json-parse-financial;67;67;69;68;80;98;101;104;102;102 16:31:57 INFO - PID 1662 | |9;json-stringify-tinderbox;60;61;53;66;93;66;58;57;61;54 16:31:57 INFO - PID 1662 | |10;stanford-crypto-aes;86;99;109;85;81;87;102;99;84;84 16:31:57 INFO - PID 1662 | |11;stanford-crypto-ccm;160;155;158;153;159;138;140;142;189;144 16:31:57 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;181;191;192;199;181;242;239;189;194;181 16:31:57 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;70;70;68;68;66;79;70;71;68;71 16:31:57 INFO - PID 1662 | __end_tp_report 16:31:57 INFO - PID 1662 | __start_cc_report 16:31:57 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1009 16:31:57 INFO - PID 1662 | __end_cc_report 16:31:57 INFO - PID 1662 | __startTimestamp1490484717354__endTimestamp 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | ------- Summary: start ------- 16:31:57 INFO - PID 1662 | Number of tests: 14 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:4.60 (3.9%) stddev-sans-first:1.45 16:31:57 INFO - PID 1662 | Values: 104.0 121.0 117.0 118.0 118.0 119.0 117.0 117.0 116.0 118.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:146.50 stddev:24.06 (16.4%) stddev-sans-first:25.38 16:31:57 INFO - PID 1662 | Values: 146.0 115.0 115.0 186.0 153.0 105.0 147.0 156.0 135.0 130.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:203.30 Median:202.00 stddev:10.04 (5.0%) stddev-sans-first:10.20 16:31:57 INFO - PID 1662 | Values: 195.0 202.0 207.0 214.0 226.0 197.0 202.0 199.0 198.0 193.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.50 stddev:10.41 (16.9%) stddev-sans-first:9.40 16:31:57 INFO - PID 1662 | Values: 81.0 59.0 58.0 86.0 59.0 62.0 59.0 61.0 72.0 58.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:70.50 stddev:17.59 (24.9%) stddev-sans-first:18.66 16:31:57 INFO - PID 1662 | Values: 88.0 70.0 88.0 71.0 70.0 70.0 88.0 106.0 107.0 117.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:86.50 stddev:22.03 (25.5%) stddev-sans-first:23.15 16:31:57 INFO - PID 1662 | Values: 91.0 88.0 136.0 92.0 90.0 117.0 81.0 79.0 85.0 137.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:97.50 stddev:23.72 (24.3%) stddev-sans-first:24.31 16:31:57 INFO - PID 1662 | Values: 99.0 148.0 154.0 122.0 98.0 103.0 97.0 99.0 99.0 145.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:91.50 stddev:2.91 (3.2%) stddev-sans-first:3.07 16:31:57 INFO - PID 1662 | Values: 90.0 94.0 92.0 91.0 91.0 92.0 91.0 92.0 91.0 83.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:85.80 Median:67.50 stddev:16.92 (25.1%) stddev-sans-first:16.52 16:31:57 INFO - PID 1662 | Values: 67.0 67.0 69.0 68.0 80.0 98.0 101.0 104.0 102.0 102.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:61.00 stddev:11.44 (18.7%) stddev-sans-first:12.08 16:31:57 INFO - PID 1662 | Values: 60.0 61.0 53.0 66.0 93.0 66.0 58.0 57.0 61.0 54.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:85.50 stddev:9.69 (11.3%) stddev-sans-first:10.06 16:31:57 INFO - PID 1662 | Values: 86.0 99.0 109.0 85.0 81.0 87.0 102.0 99.0 84.0 84.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:156.50 stddev:14.91 (9.5%) stddev-sans-first:15.64 16:31:57 INFO - PID 1662 | Values: 160.0 155.0 158.0 153.0 159.0 138.0 140.0 142.0 189.0 144.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.90 Median:193.00 stddev:22.75 (11.8%) stddev-sans-first:23.19 16:31:57 INFO - PID 1662 | Values: 181.0 191.0 192.0 199.0 181.0 242.0 239.0 189.0 194.0 181.0 16:31:57 INFO - PID 1662 | 16:31:57 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:3.51 (5.0%) stddev-sans-first:3.72 16:31:57 INFO - PID 1662 | Values: 70.0 70.0 68.0 68.0 66.0 79.0 70.0 71.0 68.0 71.0 16:31:57 INFO - PID 1662 | -------- Summary: end -------- 16:31:57 INFO - PID 1662 | 16:31:58 INFO - TEST-INFO | 1662: exit 0 16:31:58 INFO - TEST-OK | kraken | took 61911ms 16:31:58 INFO - SUITE-END | took 1179s 16:31:58 INFO - Completed test suite (00:19:39) 16:31:58 INFO - javascript benchmark 16:31:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7693.174357493211, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3117.7661675374347, 3572.214112895164, 3589.8205089745506, 3613.132848116452, 3621.203335266242, 6113.32753397126, 6182.041783523556, 6186.381361863817, 6215.440610345068, 6240.84397890053, 6126.884121540787, 6142.437386315937, 6159.846003849906, 6222.9688851555675, 6231.719572619231, 5613.0, 5643.8589035274035, 5748.971255143719, 5763.221965034709, 5819.476247137757, 5612.775488980456, 5694.857628559278, 5796.217510636074, 5817.970910145444, 5836.270466191726, 5587.38538760736, 5683.204351390808, 5733.426657334275, 5749.425057494259, 5804.912926306109, 5629.071203251499, 5663.603547751659, 5690.089585666315, 5692.203091567184, 5756.740946657411, 5565.638233514808, 5595.804146854841, 5612.214289999403, 5618.494335509783, 5665.490105890449, 5980.073088671263, 6118.497354391992, 6186.195794546725, 6218.937810621881, 6251.156093927352, 6478.4169424752, 6616.636085015322, 6664.133662623828, 6668.766593169215, 6694.397504224595, 6803.727850885959, 6837.042814006043, 6843.589384636938, 6849.897251541231, 6861.416779573742, 6238.875222495525, 6301.96849015752, 6320.367963203643, 6331.145295385156, 6341.651209183447, 5740.655560666373, 6742.292059333798, 6769.627670478072, 6844.041834143224, 6872.140982377203, 5155.61332900034, 5385.353757549119, 6345.904811427832, 6428.710708018057, 6610.305917878553, 5506.174073888868, 5606.299212598426, 6639.750103047714, 6697.6986035628515, 6749.156355455568, 6673.299303573152, 6678.732850686015, 6697.966510167418, 6698.263191048981, 6699.296573859772, 6411.358864113645, 6514.3811337922825, 6625.469962402996, 6631.10480085192, 6700.865982680417, 5377.811776587879, 5385.973070134624, 5386.380566234933, 5397.217403476474, 5417.160340147283, 4793.281007848781, 4800.975995120072, 4809.2305231162845, 4815.0, 4835.274708793639, 6191.318954914956, 6208.906866397007, 6255.437010669061, 6270.2475702915945, 6296.874062518724, 6759.5606285591275, 6764.72941082361, 6830.829229269209, 6839.521233513706, 6849.828754281083, 6236.750529978841, 6302.464290535264, 6302.4958003359625, 6304.275008374004, 6322.652254126067, 5818.7672493100645, 5857.4435428634215, 6051.183090282843, 6067.787627433018, 6079.574429789872, 6727.93272067273, 6820.181578210647, 6875.0, 6879.89680154788, 6899.931000689929, 6762.087118238975, 6779.6271205084195, 6780.69486873082, 6793.796186114425, 6801.557898736566, 6858.725650974105, 6867.0, 6869.519133660796, 6880.415164711056, 6910.7581234656545, 5471.234027236111, 6387.457066149429, 6471.029345598198, 6617.66911654425, 6642.468602511691, 6770.6614669265355, 6774.627395493098, 6775.085363476065, 6780.118584583973, 6788.59268443895, 6220.0, 6314.958031924681, 6464.644444555594, 6514.609123452608, 6530.934690653223, 6548.0832683423405, 6561.96719016421, 6580.440662543738, 6592.967035164986, 6598.901016484852, 5210.6613070150315, 6221.96889015552, 6286.308506064421, 6292.2134733158355, 6340.4293613575, 6496.350364963655, 6580.703868325841, 6587.242467116343, 6596.208454985433, 6601.042848786995, 6401.039844023434, 6409.166808314889, 6409.327020662763, 6412.294647588668, 6450.548461607643], "value": 6123.333393869397, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.9861002779943, 775.891375207471, 1208.667616405488, 1220.3410158514403, 1242.0374209987258, 39400.60599394005, 40085.79957100218, 40251.79874100629, 40278.79860600697, 40316.0, 38947.0, 39410.8029459853, 39453.0, 39651.0, 39663.80168099163, 8179.46833455825, 8629.093945135764, 8810.691625793099, 8853.0, 8863.556822158898, 8691.65279381697, 8774.692885749, 8865.024847266795, 8869.600867960926, 8905.243054340388, 8936.8659470108, 9002.549872506348, 9013.278937684969, 9024.684136055239, 9037.502937338444, 8948.64205431782, 8961.05908879568, 8975.461472311683, 8976.326775491863, 8985.23625491834, 8635.52504612246, 8662.740117796477, 8693.652253909837, 8699.826003479895, 8718.476891386537, 38822.41766373507, 38836.611633883585, 39005.82982510501, 39072.0, 39147.804260978795, 2988.835614041227, 2992.895248666308, 3003.714647108521, 3003.8498075096377, 3007.879684812605, 28108.71891281102, 28222.012229572072, 28233.717662823314, 28567.714322856922, 28571.0, 2800.8179468334492, 2823.1953893140562, 2824.703406142346, 2827.4627820714204, 2856.657201135856, 354896.0, 359715.0, 360295.0, 360681.0, 361523.0, 40176.0, 40463.595364046574, 47720.522794771605, 48385.032299353814, 48877.0, 43206.56793432026, 47943.0, 50437.0, 50437.74781126144, 50592.49407505951, 14420.639484012983, 14447.927760361341, 14535.709285814224, 14627.634309142357, 14680.119192848464, 11628.709282267842, 11636.476358563885, 11700.35648039366, 11704.180707350404, 11717.00405465528, 13785.035047546777, 13786.448542058406, 13790.448382064806, 13843.0, 13915.512957046454, 6081.0, 6087.786927457518, 6088.360722124202, 6097.481714054266, 6113.510919126459, 965.3098034905076, 972.8783902012107, 980.1227901028682, 986.1026465915982, 1012.9088382045651, 22069.558608828054, 24394.146204883098, 24551.0, 24555.754442455705, 25396.0, 1020.5866624017285, 1045.430240518919, 1079.0989523747758, 1088.4775307852276, 1095.1950316517352, 10317.638882639072, 12819.807702884464, 12827.743445131231, 12878.09853310278, 12987.155834870704, 406762.0, 475782.0, 478208.0, 480058.59970700624, 480467.0, 45144.54855451403, 56070.15894761582, 56149.43850561524, 56186.0, 56278.59303517445, 1472.9189894555689, 1493.1787516865681, 1536.9462068827538, 1540.4839378808, 1544.691061787647, 24107.397315067265, 24244.51510969806, 24281.878590606935, 24331.63502547428, 24393.756062439148, 40471.0, 40658.39012414757, 40816.97957551085, 40884.79557602193, 40903.0, 462498.0, 465209.0, 468818.0, 469009.0, 469341.0, 26074.0, 26408.8679556601, 29083.982060627775, 30533.389332212788, 33934.490982635776, 33568.3286334267, 38076.0, 38838.6116138835, 39371.80314098411, 39717.80141099276, 27683.861580692774, 27781.027664031662, 28063.0, 28489.43021139524, 28804.0, 28060.43879122447, 28064.0, 28273.43453130967, 28555.143345699664, 28650.14049578516, 25424.872875635505, 33478.0, 34463.82768086143, 34505.96482105541, 34640.78757243585, 28758.281042974093, 29085.563716543846, 29795.55306670445, 30046.54930176093, 30310.242243944078, 22713.545729085658, 23488.060477580704, 23752.812359381616, 23960.40098997539, 24048.759512404653], "value": 17791.29092242355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.220567984513, 381.46785234597735, 423.59123445874746, 436.6135969666845, 446.09442831052974, 20348.796512034893, 20917.372478825622, 21257.893710531425, 21330.36008919733, 21354.893225533888, 22014.44963875904, 22067.558648827013, 22253.0, 22817.54364912701, 22818.20136295226, 6082.0, 6328.1773369462135, 6355.9364406356035, 6370.267419246799, 6370.649614271213, 6017.097435384683, 6164.7534098636, 6185.072239164117, 6247.969085100953, 6251.499880009611, 5710.14347847822, 5886.793962211324, 6167.414095660905, 6206.379362063803, 6216.129741836146, 5939.346671866091, 6114.938850611481, 6178.536609754242, 6208.689565521706, 6222.0666899965145, 5344.305240318734, 5429.375621803503, 5534.723263836792, 5534.944650553523, 5545.195946587763, 27084.59373109405, 27254.45491090187, 27339.725493527014, 27425.86287068572, 27479.725202748115, 1792.5339411752907, 2068.524239424926, 2082.0422605601443, 2085.113826623679, 2138.155428605692, 15227.923860380737, 15773.763393549107, 16447.506574802774, 16692.332306707718, 17004.659906801793, 1736.2794440307257, 1739.625980414217, 1742.9799398493235, 1748.2133040131864, 1769.1172364274566, 163982.0, 173231.0, 174700.99999999872, 175105.0, 175893.0, 10799.406032668121, 12927.870721292667, 13029.915213565973, 13186.802197966847, 13253.271070091238, 16784.328626855033, 16790.74813877793, 16801.24394402255, 16848.494545163663, 16876.73424493168, 7158.319959603829, 7887.250711182429, 9187.540622968825, 9191.264698824078, 9245.676401325922, 7305.598192099379, 7321.450891183183, 8624.827503449897, 8881.866771998426, 8888.066752990842, 6454.677266136674, 8062.516249025077, 8093.109757926623, 8098.919010809935, 8120.837583248302, 2117.25895936421, 2134.893255337227, 2135.775743546936, 2142.742870855507, 2334.323046316549, 813.2954392741121, 908.8091500784909, 915.190056799737, 935.1677007463363, 962.6967505235829, 20676.379708608765, 21202.57594848125, 21222.681659775117, 21254.043568039477, 21735.23926662559, 929.4051806843626, 956.7703751099825, 960.9333639659972, 971.7910649210454, 985.1675334342469, 9789.461579613047, 11019.559217631366, 11043.0, 11074.501647425885, 11126.833097503382, 698661.0, 703782.0, 705190.4740476265, 719499.0, 728124.0, 155960.0, 161892.0, 163903.0, 166840.3315966849, 169154.0, 1143.8627364716288, 1167.1246565076192, 1190.6249531397582, 1199.4151122352328, 1222.6270987348944, 21114.94425278699, 21685.241016564345, 21939.780602193772, 22023.66964495566, 22114.447138821015, 62758.68620656867, 63777.681111594145, 64044.398890028126, 64834.35165648283, 65482.34517654763, 89193.55403222943, 89320.0, 91559.0, 91741.0, 91779.0, 8681.305495560466, 8860.601272942604, 8906.510141941997, 8947.731568052968, 9193.034731353378, 13780.724385512434, 13857.099288546213, 13879.514217002357, 14248.501302454364, 14394.928025359806, 9647.517624118906, 11750.118741094622, 11789.823152652889, 11898.0, 11910.225835320676, 9460.668876589287, 9985.201183905416, 11330.37682927447, 11352.432378381212, 11461.140414468782, 11691.0, 11800.409979500819, 12087.637370878887, 12134.393280335771, 12207.389630518615, 8680.0, 8680.348973826862, 8873.334499912404, 8923.0, 9092.136247056518, 11517.25137866036, 11656.825147622962, 11857.051435885282, 11968.521259149536, 12057.035437165181], "value": 10555.750685877385, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1168.4975460551962, 1432.090622454741, 1436.9869242184254, 1446.3202294921396, 1452.353702602342, 10577.894221057788, 10959.83560246597, 11009.449527523631, 11021.0, 11172.994430501254, 11058.336499810017, 11066.833997490025, 11172.776544469105, 11230.270032447885, 11240.718982025453, 2431.3435372449426, 2499.5875680512695, 2504.949901001979, 2510.535550923076, 2511.0, 2401.903923843044, 2446.2783478873685, 2474.2206205045354, 2474.6782918220606, 2497.837640553358, 2201.9559608807813, 2353.305774796439, 2397.2688330059327, 2433.8904749286235, 2437.549053425122, 2391.6771235883107, 2455.484348286862, 2464.445499762548, 2469.6789417375717, 2481.255623312999, 1968.4291555448904, 1980.6137803128395, 2484.6148846928754, 2485.5898776701824, 2624.7768939640155, 10952.726181845517, 11060.778784424267, 11085.0, 11213.439328033566, 11219.38293393863, 6233.719482623293, 6454.838629034265, 6463.709133088975, 6480.4815614750705, 6483.22201335838, 3843.7885916274595, 3851.1912498375373, 3899.1811719538646, 3935.976646072042, 4033.233685599727, 1171.2152857585354, 1181.1967861853936, 1188.4176753390864, 1206.4993027893413, 1224.7856625090662, 11722.062235021178, 14260.999999999896, 15389.692206155925, 15522.611934701723, 15532.145731984736, 5350.705711185922, 5541.944580554143, 5580.386157522669, 5583.134614134816, 5646.774129034792, 10902.236843420884, 11456.656300311004, 11518.36648984314, 11590.13074019452, 11618.825717614243, 6136.9079463808075, 6322.841928951813, 6342.429181373606, 6349.587276826992, 6354.396332348512, 5915.1718759373725, 5965.13505541694, 5980.820575382745, 6047.485963693135, 6057.757689692363, 4266.573342665709, 4298.978505107454, 4378.737275763465, 4382.53983331752, 4443.244648409778, 1107.2304748200086, 1281.6923938254752, 1287.0797379873436, 1299.7140629061591, 1310.606817954629, 641.6246495800009, 762.7902326860158, 791.2838880812874, 794.1026639896995, 826.8428998490267, 6227.408396202353, 6592.30780768022, 6682.63245521501, 6734.292899245509, 6735.595864248161, 877.2981614708208, 901.9458832470073, 902.8329758994607, 904.2991681446931, 935.8783358163395, 4065.0, 4125.855595054159, 4199.139176468878, 4202.978985105116, 4230.936535951963, 13743.175409475463, 14176.362063707158, 14249.928750356183, 14259.857401426061, 14276.286185690882, 13883.514077007458, 14099.295035248197, 14159.433622655184, 14169.22069286199, 14200.573982780534, 9779.51102444875, 10214.64248751305, 10230.795384092276, 10238.641647542301, 10243.38539687621, 6472.838179045561, 6615.272320044695, 6660.900086498803, 6890.689918953559, 6999.755008574675, 12377.51469823627, 12454.25274483534, 12518.937405312916, 12646.683832904251, 12712.491500340253, 10969.945150274198, 11819.704507387383, 12078.27530348182, 12724.491020359083, 12909.483620655068, 8843.248323892542, 9234.769130771785, 9242.260619150586, 9255.861162082709, 9269.397489163182, 10030.548625312025, 10137.746556335856, 10173.949130254301, 10252.38485690861, 10294.742631433974, 8537.09117257567, 8734.30125589964, 8821.955890220508, 8852.0, 8929.910700892908, 8455.74632761018, 8493.32053435736, 8517.0, 8563.486190828571, 8592.269657079218, 7776.688932442639, 7818.882716759367, 7857.253560911704, 7924.4056695746895, 7930.206979302024, 5633.633813802254, 5639.54883609302, 5820.534357251495, 5905.35041145465, 6014.338422773404, 5194.3766747990485, 5889.882202355843, 5954.672493012926, 5983.581149319506, 6035.78874739382], "value": 5209.583091942771, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.3075821604443, 329.8169515918664, 356.38167778903596, 390.39293897988654, 412.9194807012632, 6161.969190154055, 6214.223222097238, 6255.244336116994, 6303.3066362700065, 6339.654327898684, 5293.947060529394, 6072.939270607292, 6191.4737247333915, 6243.56295059346, 6263.592866463688, 3635.7273204509634, 4545.977155140084, 4705.3412522247, 4712.175369310374, 4729.787159577827, 3991.580884007181, 4661.743604101773, 4692.882677933062, 4697.13103075932, 4742.834000809972, 4689.437267527901, 4695.3191787190835, 4713.5286471352765, 4715.221988371914, 4715.976420117911, 3557.3418917500344, 3925.450436938831, 4523.932141017887, 4681.368015317923, 4722.763861806896, 3284.868605255787, 3983.9641693159697, 4001.7598944063157, 4019.356902895552, 4022.5374081980654, 5441.646292990943, 5931.495822855062, 6143.416375444371, 6164.044573091178, 6179.289381721114, 5297.761600727977, 5440.4287549807495, 5468.152436372368, 5470.589705772083, 5495.148252020903, 6668.366505182, 6671.5997040177745, 6686.93313066868, 6693.665316734193, 6709.429698475636, 3682.4844521766972, 3748.8565987374113, 4485.798139083749, 4485.977570112129, 4507.774611269423, 5469.456702633294, 6715.597064176165, 6900.516963812485, 7122.5726456412785, 7197.316254955771, 3767.0, 4401.977990110029, 4522.773861306987, 4527.822766080777, 4538.636909047268, 4594.839180628729, 4612.492625811158, 4619.5611416915335, 4628.328892310664, 4640.95578494836, 5306.336707911511, 5368.275282836845, 5412.702301373383, 5467.152591348347, 5467.4532546745, 6342.904856427157, 6726.192856857208, 6726.730930762715, 6736.6631668416385, 6746.22418421878, 1373.8557451467652, 1521.767368431574, 1574.2994367506578, 1577.7948866647262, 1588.602849287678, 909.8672153169214, 1011.676263595657, 1037.387941114746, 1039.6673064619401, 1042.6819819954835, 5304.0, 6177.660228687376, 6281.842953926189, 6295.181626388632, 6323.588966717149, 5130.820421285237, 6020.668863212565, 6728.562643428161, 6864.24493305736, 6983.0, 6229.065640153923, 6233.532485063548, 6274.0, 6312.4634406076, 6328.77875752506, 3619.077135330474, 3627.6916462101017, 3633.4913112164318, 3656.2321912398693, 3672.926541469156, 6136.527233463937, 6791.4906382021545, 7096.680649370791, 7161.928380716126, 7164.892526612, 5501.724913754297, 5841.88316233673, 6206.0, 7204.279572042754, 7213.242609526029, 6937.965310173417, 7103.964480177669, 7130.821729456701, 7155.642217888981, 7163.89254161188, 6052.60790018307, 6070.362611925684, 6943.6875340608785, 6968.930310696828, 7007.229204787572, 6130.601510901777, 6558.573692709959, 6624.8012559623285, 6865.828354290983, 6896.7586134485055, 6779.15260592426, 6912.585244885323, 6967.163940327193, 7058.505904586835, 7134.857302854018, 4526.751028693454, 4527.615152712056, 4528.343390208335, 4549.795259213277, 4559.065391594649, 6217.4715149212825, 6563.803085907431, 6663.26704062543, 6887.586744795329, 6992.965035174791, 6779.796606101825, 6802.3537763912345, 6809.489288303298, 6827.249002609609, 6828.863422731617, 5839.328477225174, 6583.769568065286, 6761.22245941723, 6770.763023294161, 6776.390124888783, 6642.568233064835, 6648.8139251384, 6661.433778128913, 6667.599944003376, 6673.265940746417, 6539.378759017885, 6798.456123510206, 6809.591424514545, 6822.795316140524, 6879.243283238739, 6523.966355644648, 6542.0, 6610.0, 6616.8420526408645, 6628.5691430056895], "value": 4850.951245468749, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.7428464325877, 724.9130104387473, 726.7928640337504, 728.7048745258172, 731.952423092499, 12845.678858028554, 14977.47578834741, 15551.300191491382, 15608.453704120358, 15650.53048408548, 14348.42606295747, 14496.782548261757, 14498.42006319746, 14515.854841451583, 15022.399104035825, 913.4884464699757, 918.9540522973858, 942.8067246214544, 953.8521529162956, 960.577345967772, 956.4548207521715, 967.8016006718606, 968.9370190937602, 970.7136394763562, 976.6288810252095, 915.1397686175007, 957.6839642917855, 998.3560603410796, 999.7200783780553, 1014.345746993189, 958.7219706285169, 1002.6791426743408, 1006.6174853555602, 1008.8486726990936, 1011.4285428732743, 658.1641315529251, 745.944054195932, 750.6847124207845, 751.2374939436477, 752.5259086775351, 16009.759853602205, 16133.515994520183, 16313.265903034391, 16399.344026239054, 16659.583510412216, 14542.345594448276, 14547.70904581913, 14619.365990800798, 14748.005846523758, 14757.631059223499, 11499.942500287529, 11613.825792613117, 11786.705332366673, 12065.0, 12223.69440763979, 2907.898223562165, 2932.736053755172, 2952.5866378706996, 3019.864106115208, 3021.305099827052, 87390.56304718499, 96113.51943240239, 108111.01431238333, 108392.45803770931, 110162.9999999992, 10425.582976681, 11666.76666466683, 11716.602185366319, 11842.763144737057, 12063.914247717748, 22074.558508830054, 22121.33635990923, 24756.0, 25035.0, 25384.619230711553, 6502.0246962955935, 6591.0, 7311.451641126937, 7602.353799926957, 7788.299053085249, 4042.292598795199, 4123.814428350672, 4130.359794231899, 4131.97934010328, 4132.8140233689555, 3896.6103389661375, 3950.7234493585174, 3994.6005399460405, 3994.6205110514456, 3998.620131087542, 782.4796510320687, 790.5928446849877, 804.4931693033468, 807.4993504027541, 807.5356669914822, 544.5942772634413, 559.7621011070279, 579.4408395897979, 582.5543459253674, 597.333972620525, 8913.55432228399, 8991.730248092568, 8999.775005624912, 9868.0, 9899.65351212704, 540.187018537107, 578.2800413485154, 589.2899056636743, 598.1715324275909, 607.3501353551658, 4535.614472769851, 4652.744099074515, 4703.223968045234, 4715.528447155326, 4795.886398559808, 95142.1558089649, 98989.0, 99697.0, 101620.98379016115, 101980.0, 71151.0, 89256.0, 89429.0, 90961.0, 91369.0, 71488.21279468056, 72069.0, 73006.0, 73792.63103684447, 74637.88043179357, 8646.524441155796, 9027.277817774693, 9322.161005509537, 9335.346525743133, 9372.859407108763, 31446.899358522343, 36556.0, 38428.42357364586, 39134.99999999886, 40036.59963400445, 44309.77845110754, 47749.76125119352, 51190.74404628102, 52054.479455204964, 52128.43614691565, 9492.335536512377, 11134.610288639858, 11540.365279909685, 11584.652460426201, 11617.419129043683, 12308.630741077783, 12345.753084938431, 12361.443735031766, 12406.0, 12591.685207869876, 11473.311601303949, 11529.884701152881, 11592.420378981185, 11748.941255293668, 11956.641300760992, 11540.596079137189, 11648.41757912084, 11796.823047654463, 11797.528098876292, 12109.576164834189, 11160.0, 11245.437728113726, 11367.54529818831, 11731.178817483036, 11738.119641027128, 6445.452136568444, 7472.476926615303, 8499.405041647025, 8564.314854811724, 8650.610722517627, 7499.437542184249, 8787.824243515222, 9090.909090909006, 9148.542572871464, 9637.9036209637], "value": 7133.251688941014, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 121.0, 117.0, 118.0, 118.0, 119.0, 117.0, 117.0, 116.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 115.0, 186.0, 153.0, 105.0, 147.0, 156.0, 135.0, 130.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 207.0, 214.0, 226.0, 197.0, 202.0, 199.0, 198.0, 193.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 86.0, 59.0, 62.0, 59.0, 61.0, 72.0, 58.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 88.0, 71.0, 70.0, 70.0, 88.0, 106.0, 107.0, 117.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 136.0, 92.0, 90.0, 117.0, 81.0, 79.0, 85.0, 137.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 148.0, 154.0, 122.0, 98.0, 103.0, 97.0, 99.0, 99.0, 145.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 92.0, 91.0, 91.0, 92.0, 91.0, 92.0, 91.0, 83.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 68.0, 80.0, 98.0, 101.0, 104.0, 102.0, 102.0], "value": 85.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 53.0, 66.0, 93.0, 66.0, 58.0, 57.0, 61.0, 54.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 99.0, 109.0, 85.0, 81.0, 87.0, 102.0, 99.0, 84.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 155.0, 158.0, 153.0, 159.0, 138.0, 140.0, 142.0, 189.0, 144.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 191.0, 192.0, 199.0, 181.0, 242.0, 239.0, 189.0, 194.0, 181.0], "value": 198.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 68.0, 66.0, 79.0, 70.0, 71.0, 68.0, 71.0], "value": 70.1, "unit": "score"}]}]} 16:31:58 INFO - Return code: 0 16:31:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:58 ERROR - # TBPL SUCCESS # 16:31:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:58 INFO - 2017-03-25 16:31:58.666 screenresolution[1667:12575] starting screenresolution argv=screenresolution get 16:31:58 INFO - 2017-03-25 16:31:58.669 screenresolution[1667:12575] Display 0: 1600x1200x32@60 16:31:58 INFO - 2017-03-25 16:31:58.675 screenresolution[1668:12578] starting screenresolution argv=screenresolution list 16:31:58 INFO - Available Modes on Display 0 16:31:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:58 INFO - Intel Iris: 16:31:58 INFO - Chipset Model: Intel Iris 16:31:58 INFO - Type: GPU 16:31:58 INFO - Bus: Built-In 16:31:58 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:58 INFO - Vendor: Intel (0x8086) 16:31:58 INFO - Device ID: 0x0a2e 16:31:58 INFO - Revision ID: 0x0009 16:31:58 INFO - Displays: 16:31:58 INFO - Display: 16:31:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:58 INFO - Display Serial Number: 1600x1200 60 16:31:58 INFO - Main Display: Yes 16:31:58 INFO - Mirror: Off 16:31:58 INFO - Online: Yes 16:31:58 INFO - Rotation: Supported 16:31:58 INFO - Return code: 0 16:31:58 INFO - Running post-action listener: _resource_record_post_action 16:31:58 INFO - [mozharness: 2017-03-25 23:31:58.924464Z] Finished run-tests step (success) 16:31:58 INFO - Running post-run listener: _resource_record_post_run 16:31:58 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 34386432; Write bytes: 521961984; Read time: 317; Write time: 1633 16:31:58 INFO - TinderboxPrint: CPU usage
54.1% 16:31:58 INFO - TinderboxPrint: I/O read bytes / time
34,386,432 / 317 16:31:58 INFO - TinderboxPrint: I/O write bytes / time
521,961,984 / 1,633 16:31:58 INFO - TinderboxPrint: CPU idle
2,207.4 (45.9%) 16:31:58 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 16:31:58 INFO - TinderboxPrint: CPU user
1,633.9 (34.0%) 16:31:58 INFO - TinderboxPrint: Swap in / out
242,036,736 / 0 16:31:58 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200372736; Write bytes: 196478976; Read time: 15552; Write time: 408 16:31:59 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 33432064; Write bytes: 305662464; Read time: 302; Write time: 1169 16:31:59 INFO - Running post-run listener: _upload_blobber_files 16:31:59 INFO - Blob upload gear active. 16:31:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:59 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 16:31:59 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'] 16:31:59 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 16:31:59 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:31:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:32:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:01 INFO - (blobuploader) - INFO - Done attempting. 16:32:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:32:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:32:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:02 INFO - (blobuploader) - INFO - Done attempting. 16:32:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:32:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:32:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:03 INFO - (blobuploader) - INFO - Done attempting. 16:32:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:05 INFO - (blobuploader) - INFO - Done attempting. 16:32:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:05 INFO - Return code: 0 16:32:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4efb11c872bc8d359534c85260138b08db79ada596c9f4b3c07bbe7d982b873e4cc877cf811992eeade57340e015766769587517f88feb7288f678ab0ce915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67f9f0ca05b35a3f24bf2a9c05093d7e7567163f12995b7af66f1e3d394c5d92858499eeaf5fa26c4c085edf09d009d21f03b3664a67c6f4d3157295d9d82ede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7543064f76efbd52fa955c76f8100481d28ac87a6fbf78aa960b05c94bf05aeddae869a29d6038cabbe78bc4e44f4685fe28eb407fb1f29156890dbfe4180707", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c092ea5e8b00a47cbdc6f8cd52d464bfc9cf1cc6bab1c167da034559608d88f371d2533f025621bf944aa999f8d18fc310a4dbbf1ed488c3182dc5408566481"} 16:32:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:05 INFO - Contents: 16:32:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4efb11c872bc8d359534c85260138b08db79ada596c9f4b3c07bbe7d982b873e4cc877cf811992eeade57340e015766769587517f88feb7288f678ab0ce915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67f9f0ca05b35a3f24bf2a9c05093d7e7567163f12995b7af66f1e3d394c5d92858499eeaf5fa26c4c085edf09d009d21f03b3664a67c6f4d3157295d9d82ede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7543064f76efbd52fa955c76f8100481d28ac87a6fbf78aa960b05c94bf05aeddae869a29d6038cabbe78bc4e44f4685fe28eb407fb1f29156890dbfe4180707", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c092ea5e8b00a47cbdc6f8cd52d464bfc9cf1cc6bab1c167da034559608d88f371d2533f025621bf944aa999f8d18fc310a4dbbf1ed488c3182dc5408566481"} 16:32:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:05 INFO - Copying logs to upload dir... 16:32:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.914499 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-25 16:32:05.459768) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.463099) ========= 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.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4efb11c872bc8d359534c85260138b08db79ada596c9f4b3c07bbe7d982b873e4cc877cf811992eeade57340e015766769587517f88feb7288f678ab0ce915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67f9f0ca05b35a3f24bf2a9c05093d7e7567163f12995b7af66f1e3d394c5d92858499eeaf5fa26c4c085edf09d009d21f03b3664a67c6f4d3157295d9d82ede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7543064f76efbd52fa955c76f8100481d28ac87a6fbf78aa960b05c94bf05aeddae869a29d6038cabbe78bc4e44f4685fe28eb407fb1f29156890dbfe4180707", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c092ea5e8b00a47cbdc6f8cd52d464bfc9cf1cc6bab1c167da034559608d88f371d2533f025621bf944aa999f8d18fc310a4dbbf1ed488c3182dc5408566481"} build_url:https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4efb11c872bc8d359534c85260138b08db79ada596c9f4b3c07bbe7d982b873e4cc877cf811992eeade57340e015766769587517f88feb7288f678ab0ce915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67f9f0ca05b35a3f24bf2a9c05093d7e7567163f12995b7af66f1e3d394c5d92858499eeaf5fa26c4c085edf09d009d21f03b3664a67c6f4d3157295d9d82ede", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7543064f76efbd52fa955c76f8100481d28ac87a6fbf78aa960b05c94bf05aeddae869a29d6038cabbe78bc4e44f4685fe28eb407fb1f29156890dbfe4180707", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c092ea5e8b00a47cbdc6f8cd52d464bfc9cf1cc6bab1c167da034559608d88f371d2533f025621bf944aa999f8d18fc310a4dbbf1ed488c3182dc5408566481"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.497494) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.497803) ========= 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.cjFHichNm2/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.Q7v7wXAi4x/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.004587 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.545712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 16:32:05.545990) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 16:32:05.546268) ========= ========= Total master_lag: 0.19 =========