builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0388 starttime: 1483356471.47 results: success (0) buildid: 20170102004004 builduid: b73d55e85d124ab0956ae5e3a21c011b revision: 1458ff2725619c3db4b1dcb4f057f26a60208873 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.466346) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.467243) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.467521) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.493668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.493959) ========= 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.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-01-02 03:27:51-- 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% 18.7M=0.001s 2017-01-02 03:27:51 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.223501 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.743225) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.743534) ========= 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.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.025416 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.815636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:51.816008) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1458ff2725619c3db4b1dcb4f057f26a60208873 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1458ff2725619c3db4b1dcb4f057f26a60208873 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-01-02 03:27:51,916 truncating revision to first 12 chars 2017-01-02 03:27:51,917 Setting DEBUG logging. 2017-01-02 03:27:51,917 attempt 1/10 2017-01-02 03:27:51,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1458ff272561?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 03:27:52,280 unpacking tar archive at: mozilla-aurora-1458ff272561/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:52.522414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:52.522723) ========= script_repo_revision: 1458ff2725619c3db4b1dcb4f057f26a60208873 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:52.523117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:52.523769) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 03:27:52.594036) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-02 03:27:52.594342) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:27:52 INFO - MultiFileLogger online at 20170102 03:27:52 in /builds/slave/test 03:27:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:27:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:52 INFO - {'append_to_log': False, 03:27:52 INFO - 'base_work_dir': '/builds/slave/test', 03:27:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:27:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:52 INFO - 'branch': 'Mozilla-Aurora', 03:27:52 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:52 INFO - 'config_files': ('talos/mac_config.py',), 03:27:52 INFO - 'default_actions': ('clobber', 03:27:52 INFO - 'read-buildbot-config', 03:27:52 INFO - 'download-and-extract', 03:27:52 INFO - 'populate-webroot', 03:27:52 INFO - 'create-virtualenv', 03:27:52 INFO - 'install', 03:27:52 INFO - 'run-tests'), 03:27:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:52 INFO - 'download_minidump_stackwalk': True, 03:27:52 INFO - 'download_symbols': 'ondemand', 03:27:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:52 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:52 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:52 INFO - 'installer_path': 'installer.exe', 03:27:52 INFO - 'log_level': 'info', 03:27:52 INFO - 'log_name': 'talos', 03:27:52 INFO - 'log_to_console': True, 03:27:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:27:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:27:52 INFO - 'opt_config_files': (), 03:27:52 INFO - 'pip_index': False, 03:27:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:52 INFO - 'cmd': ('bash', 03:27:52 INFO - '-c', 03:27:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:52 INFO - 'enabled': True, 03:27:52 INFO - 'halt_on_failure': False, 03:27:52 INFO - 'name': 'check_screen_resolution'},), 03:27:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:52 INFO - 'cmd': ('bash', 03:27:52 INFO - '-c', 03:27:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:52 INFO - 'enabled': True, 03:27:52 INFO - 'halt_on_failure': False, 03:27:52 INFO - 'name': 'check_screen_resolution'},), 03:27:52 INFO - 'run_cmd_checks_enabled': True, 03:27:52 INFO - 'sps_profile': False, 03:27:52 INFO - 'sps_profile_interval': 0, 03:27:52 INFO - 'suite': 'dromaeojs', 03:27:52 INFO - 'system_bits': '32', 03:27:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:52 INFO - 'title': 't-yosemite-r7-0388', 03:27:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:52 INFO - 'use_talos_json': True, 03:27:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:52 INFO - 'work_dir': 'build'} 03:27:52 INFO - [mozharness: 2017-01-02 11:27:52.799374Z] Running clobber step. 03:27:52 INFO - Running pre-action listener: _resource_record_pre_action 03:27:52 INFO - Running main action method: clobber 03:27:52 INFO - rmtree: /builds/slave/test/build 03:27:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:55 INFO - Running post-action listener: _resource_record_post_action 03:27:55 INFO - [mozharness: 2017-01-02 11:27:55.127055Z] Finished clobber step (success) 03:27:55 INFO - [mozharness: 2017-01-02 11:27:55.127214Z] Running read-buildbot-config step. 03:27:55 INFO - Running pre-action listener: _resource_record_pre_action 03:27:55 INFO - Running main action method: read_buildbot_config 03:27:55 INFO - Using buildbot properties: 03:27:55 INFO - { 03:27:55 INFO - "project": "", 03:27:55 INFO - "product": "firefox", 03:27:55 INFO - "script_repo_revision": "production", 03:27:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:27:55 INFO - "repository": "", 03:27:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:27:55 INFO - "buildid": "20170102004004", 03:27:55 INFO - "stage_platform": "macosx64", 03:27:55 INFO - "basedir": "/builds/slave/test", 03:27:55 INFO - "nightly_build": "True", 03:27:55 INFO - "buildnumber": 27, 03:27:55 INFO - "slavename": "t-yosemite-r7-0388", 03:27:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:27:55 INFO - "platform": "yosemite_r7", 03:27:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:27:55 INFO - "branch": "mozilla-aurora", 03:27:55 INFO - "repo_path": "releases/mozilla-aurora", 03:27:55 INFO - "revision": "1458ff2725619c3db4b1dcb4f057f26a60208873", 03:27:55 INFO - "pgo_build": "False", 03:27:55 INFO - "builduid": "b73d55e85d124ab0956ae5e3a21c011b", 03:27:55 INFO - "slavebuilddir": "test" 03:27:55 INFO - } 03:27:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:27:55 INFO - Running post-action listener: _resource_record_post_action 03:27:55 INFO - [mozharness: 2017-01-02 11:27:55.130612Z] Finished read-buildbot-config step (success) 03:27:55 INFO - [mozharness: 2017-01-02 11:27:55.130746Z] Running download-and-extract step. 03:27:55 INFO - Running pre-action listener: _resource_record_pre_action 03:27:55 INFO - Running main action method: download_and_extract 03:27:55 INFO - mkdir: /builds/slave/test/build/tests 03:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:55 INFO - trying https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:27:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:27:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:27:56 INFO - Downloaded 1199 bytes. 03:27:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:27:56 INFO - Using the following test package requirements: 03:27:56 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:27:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:27:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:27:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'jsshell-mac.zip'], 03:27:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:27:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:27:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:27:56 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:27:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:27:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:27:56 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:27:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:27:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:27:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:27:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:27:57 INFO - Http code: 200 03:27:57 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 03:27:57 INFO - x-amz-cf-id: E0xYvhwkpBx8jaj43_g4RE3hBxatGriWzFrk0xQO5pDKgiGvXawYqg== 03:27:57 INFO - x-amz-version-id: nu624pjemLszedJaN6vyHjLNDxZ6OBGv 03:27:57 INFO - Expected file size: 16268548 03:27:57 INFO - Obtained file size: 16268548 03:28:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:28:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:28:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:28:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:28:04 INFO - Http code: 200 03:28:04 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 03:28:04 INFO - x-amz-cf-id: XTi8wm8LyjXlyyExNQrVg5V2Jf7W0jbpRmO-PzKqkUBcLsPA4hqNCg== 03:28:04 INFO - x-amz-version-id: nu624pjemLszedJaN6vyHjLNDxZ6OBGv 03:28:04 INFO - Expected file size: 16268548 03:28:04 INFO - Obtained file size: 16268548 03:28:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:28:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:28:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:28:12 INFO - Http code: 200 03:28:12 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 03:28:12 INFO - x-amz-cf-id: 69pDIDcrIR4j79eDSlPyvNiyGVMIU21cB98v6JQ4JdBQj8NiFEV1ZQ== 03:28:12 INFO - x-amz-version-id: Q3vk0aVE1dWX_83E4zeF7B24o9vNeivR 03:28:12 INFO - Expected file size: 14409038 03:28:12 INFO - Obtained file size: 14409038 03:28:12 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')]} 03:28:12 INFO - trying https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:28:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:28:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:28:15 INFO - Downloaded 109952081 bytes. 03:28:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:28:15 INFO - mkdir: /builds/slave/test/properties 03:28:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:28:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:28:15 INFO - Contents: 03:28:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:28:16 INFO - Running post-action listener: _resource_record_post_action 03:28:16 INFO - Running post-action listener: set_extra_try_arguments 03:28:16 INFO - [mozharness: 2017-01-02 11:28:16.262092Z] Finished download-and-extract step (success) 03:28:16 INFO - [mozharness: 2017-01-02 11:28:16.262210Z] Running populate-webroot step. 03:28:16 INFO - Running pre-action listener: _resource_record_pre_action 03:28:16 INFO - Running main action method: populate_webroot 03:28:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:28:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:28:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:28:16 INFO - '--tppagecycles', 03:28:16 INFO - '7'], 03:28:16 INFO - 'tests': ['tsvgm']}}, 03:28:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:28:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:28:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:28:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:28:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['damp', 'tps']}, 03:28:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'tests': ['damp', 'tps']}, 03:28:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['dromaeo_dom']}, 03:28:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:28:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['basic_compositor_video']}, 03:28:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:28:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'other-e10s': {'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['a11yr', 03:28:16 INFO - 'ts_paint', 03:28:16 INFO - 'tpaint', 03:28:16 INFO - 'sessionrestore', 03:28:16 INFO - 'sessionrestore_no_auto_restore', 03:28:16 INFO - 'tabpaint']}, 03:28:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['tsvgx', 03:28:16 INFO - 'tsvgr_opacity', 03:28:16 INFO - 'tart', 03:28:16 INFO - 'tscrollx', 03:28:16 INFO - 'cart']}, 03:28:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:28:16 INFO - 'tsvgr_opacity', 03:28:16 INFO - 'tart', 03:28:16 INFO - 'tscrollx', 03:28:16 INFO - 'cart']}, 03:28:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'talos_options': ['--disable-e10s'], 03:28:16 INFO - 'tests': ['tp5o']}, 03:28:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'tests': ['tp5o']}, 03:28:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'talos_options': ['--disable-e10s', 03:28:16 INFO - '--xperf_path', 03:28:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:16 INFO - 'tests': ['tp5n']}, 03:28:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:28:16 INFO - 'talos_options': ['--xperf_path', 03:28:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:16 INFO - 'tests': ['tp5n']}}, 03:28:16 INFO - 'talos.zip': {'path': '', 03:28:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:28:16 INFO - Running post-action listener: _resource_record_post_action 03:28:16 INFO - [mozharness: 2017-01-02 11:28:16.280818Z] Finished populate-webroot step (success) 03:28:16 INFO - [mozharness: 2017-01-02 11:28:16.280944Z] Running create-virtualenv step. 03:28:16 INFO - Running pre-action listener: _resource_record_pre_action 03:28:16 INFO - Running main action method: create_virtualenv 03:28:16 INFO - Creating virtualenv /builds/slave/test/build/venv 03:28:16 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 03:28:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:28:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:28:16 INFO - Using real prefix '/tools/python27' 03:28:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:28:17 INFO - Installing distribute.............................................................................................................................................................................................done. 03:28:19 INFO - Installing pip.................done. 03:28:19 INFO - Return code: 0 03:28:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:28:20 INFO - Reading from file tmpfile_stdout 03:28:20 INFO - Output received: 03:28:20 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) 03:28:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 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 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:20 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 03:28:20 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 03:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:20 INFO - 'HOME': '/Users/cltbld', 03:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PAGER': '/bin/cat', 03:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:20 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - 'XPC_FLAGS': '0x0', 03:28:20 INFO - 'XPC_SERVICE_NAME': '0', 03:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:20 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 03:28:20 INFO - Cleaning up... 03:28:20 INFO - Return code: 0 03:28:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 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 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:20 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 03:28:20 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 03:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:20 INFO - 'HOME': '/Users/cltbld', 03:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PAGER': '/bin/cat', 03:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:20 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - 'XPC_FLAGS': '0x0', 03:28:20 INFO - 'XPC_SERVICE_NAME': '0', 03:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:20 INFO - Downloading/unpacking psutil>=3.1.1 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:28:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:24 INFO - Installing collected packages: psutil 03:28:24 INFO - Running setup.py install for psutil 03:28:24 INFO - building 'psutil._psutil_osx' extension 03:28:24 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 03:28:25 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 03:28:25 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 03:28:25 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 03:28:25 INFO - building 'psutil._psutil_posix' extension 03:28:25 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 03:28:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:28:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:28:25 INFO - ^ 03:28:25 INFO - 1 warning generated. 03:28:25 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 03:28:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:25 INFO - Successfully installed psutil 03:28:25 INFO - Cleaning up... 03:28:25 INFO - Return code: 0 03:28:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:25 INFO - 'HOME': '/Users/cltbld', 03:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:25 INFO - 'LOGNAME': 'cltbld', 03:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:25 INFO - 'MOZ_NO_REMOTE': '1', 03:28:25 INFO - 'NO_EM_RESTART': '1', 03:28:25 INFO - 'PAGER': '/bin/cat', 03:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:25 INFO - 'PWD': '/builds/slave/test', 03:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:25 INFO - 'SHELL': '/bin/bash', 03:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:25 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:25 INFO - 'USER': 'cltbld', 03:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:25 INFO - 'XPC_FLAGS': '0x0', 03:28:25 INFO - 'XPC_SERVICE_NAME': '0', 03:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:28:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:28:28 INFO - Installing collected packages: mozsystemmonitor 03:28:28 INFO - Running setup.py install for mozsystemmonitor 03:28:28 INFO - Successfully installed mozsystemmonitor 03:28:28 INFO - Cleaning up... 03:28:29 INFO - Return code: 0 03:28:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28: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')]} 03:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:28:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:28:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:29 INFO - 'HOME': '/Users/cltbld', 03:28:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:29 INFO - 'LOGNAME': 'cltbld', 03:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:29 INFO - 'MOZ_NO_REMOTE': '1', 03:28:29 INFO - 'NO_EM_RESTART': '1', 03:28:29 INFO - 'PAGER': '/bin/cat', 03:28:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:29 INFO - 'PWD': '/builds/slave/test', 03:28:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:29 INFO - 'SHELL': '/bin/bash', 03:28:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:29 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:29 INFO - 'USER': 'cltbld', 03:28:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:29 INFO - 'XPC_FLAGS': '0x0', 03:28:29 INFO - 'XPC_SERVICE_NAME': '0', 03:28:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:29 INFO - Downloading/unpacking jsonschema==2.5.1 03:28: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 03:28: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 03:28: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 03:28: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 03:28: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 03:28: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 03:28:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:28:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:28:32 INFO - warning: no files found matching '*.txt' 03:28:32 INFO - no previously-included directories found matching 'build' 03:28:32 INFO - no previously-included directories found matching 'dist' 03:28:32 INFO - no previously-included directories found matching '.git*' 03:28:32 INFO - Installing collected packages: jsonschema, functools32 03:28:32 INFO - Running setup.py install for functools32 03:28:32 INFO - warning: no files found matching '*.txt' 03:28:32 INFO - no previously-included directories found matching 'build' 03:28:32 INFO - no previously-included directories found matching 'dist' 03:28:32 INFO - no previously-included directories found matching '.git*' 03:28:32 INFO - Successfully installed jsonschema functools32 03:28:32 INFO - Cleaning up... 03:28:32 INFO - Return code: 0 03:28:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:32 INFO - 'HOME': '/Users/cltbld', 03:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:32 INFO - 'LOGNAME': 'cltbld', 03:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:32 INFO - 'MOZ_NO_REMOTE': '1', 03:28:32 INFO - 'NO_EM_RESTART': '1', 03:28:32 INFO - 'PAGER': '/bin/cat', 03:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:32 INFO - 'PWD': '/builds/slave/test', 03:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:32 INFO - 'SHELL': '/bin/bash', 03:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:32 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:32 INFO - 'USER': 'cltbld', 03:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:32 INFO - 'XPC_FLAGS': '0x0', 03:28:32 INFO - 'XPC_SERVICE_NAME': '0', 03:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:28:32 INFO - Cleaning up... 03:28:32 INFO - Return code: 0 03:28:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:32 INFO - 'HOME': '/Users/cltbld', 03:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:32 INFO - 'LOGNAME': 'cltbld', 03:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:32 INFO - 'MOZ_NO_REMOTE': '1', 03:28:32 INFO - 'NO_EM_RESTART': '1', 03:28:32 INFO - 'PAGER': '/bin/cat', 03:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:32 INFO - 'PWD': '/builds/slave/test', 03:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:32 INFO - 'SHELL': '/bin/bash', 03:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:32 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:32 INFO - 'USER': 'cltbld', 03:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:32 INFO - 'XPC_FLAGS': '0x0', 03:28:32 INFO - 'XPC_SERVICE_NAME': '0', 03:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:33 INFO - Downloading/unpacking blobuploader==1.2.4 03:28:33 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 03:28:33 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 03:28:33 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 03:28:33 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 03:28:33 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 03:28:33 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 03:28:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:28:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:28:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:37 INFO - Downloading docopt-0.6.1.tar.gz 03:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:37 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:37 INFO - Running setup.py install for blobuploader 03:28:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:37 INFO - Running setup.py install for requests 03:28:37 INFO - Running setup.py install for docopt 03:28:37 INFO - Successfully installed blobuploader requests docopt 03:28:37 INFO - Cleaning up... 03:28:38 INFO - Return code: 0 03:28:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:38 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')]} 03:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:38 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')]} 03:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:38 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 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:38 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 03:28:38 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 03:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:38 INFO - 'HOME': '/Users/cltbld', 03:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:38 INFO - 'LOGNAME': 'cltbld', 03:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:38 INFO - 'MOZ_NO_REMOTE': '1', 03:28:38 INFO - 'NO_EM_RESTART': '1', 03:28:38 INFO - 'PAGER': '/bin/cat', 03:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:38 INFO - 'PWD': '/builds/slave/test', 03:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:38 INFO - 'SHELL': '/bin/bash', 03:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:38 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:38 INFO - 'USER': 'cltbld', 03:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:38 INFO - 'XPC_FLAGS': '0x0', 03:28:38 INFO - 'XPC_SERVICE_NAME': '0', 03:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-FDKNsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-eCjtAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-OkBrP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-rfOJMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-em8lSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Gwefk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:38 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-CxypbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1xB7DG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5KD_Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-_wUMtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Xd7Mar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-KnjPSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-vBhqdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-gA4bRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-pYHN__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-DyDHHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-bhFsI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:40 INFO - Running setup.py install for manifestparser 03:28:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:40 INFO - Running setup.py install for mozcrash 03:28:40 INFO - Running setup.py install for mozdebug 03:28:40 INFO - Running setup.py install for mozdevice 03:28:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:28:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:40 INFO - Running setup.py install for mozfile 03:28:40 INFO - Running setup.py install for mozhttpd 03:28:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:40 INFO - Running setup.py install for mozinfo 03:28:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Running setup.py install for mozInstall 03:28:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Running setup.py install for mozleak 03:28:41 INFO - Running setup.py install for mozlog 03:28:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Running setup.py install for moznetwork 03:28:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:41 INFO - Running setup.py install for mozprocess 03:28:41 INFO - Running setup.py install for mozprofile 03:28:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:42 INFO - Running setup.py install for mozrunner 03:28:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:42 INFO - Running setup.py install for mozscreenshot 03:28:42 INFO - Running setup.py install for moztest 03:28:42 INFO - Running setup.py install for mozversion 03:28:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:42 INFO - Cleaning up... 03:28:42 INFO - Return code: 0 03:28:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:28:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:42 INFO - 'HOME': '/Users/cltbld', 03:28:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:42 INFO - 'LOGNAME': 'cltbld', 03:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:42 INFO - 'MOZ_NO_REMOTE': '1', 03:28:42 INFO - 'NO_EM_RESTART': '1', 03:28:42 INFO - 'PAGER': '/bin/cat', 03:28:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:42 INFO - 'PWD': '/builds/slave/test', 03:28:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:42 INFO - 'SHELL': '/bin/bash', 03:28:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:42 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:42 INFO - 'USER': 'cltbld', 03:28:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:42 INFO - 'XPC_FLAGS': '0x0', 03:28:42 INFO - 'XPC_SERVICE_NAME': '0', 03:28:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:42 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-VjsKiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:43 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)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-zNRqT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:43 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)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-2WpwrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:43 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)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-78GUJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-CaNvmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:43 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)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Signmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5ajji4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-z07KHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-wya6v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dgfK82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:43 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)) 03:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:43 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-fCF6xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:44 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)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-uzCCAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-0VUS_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:44 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)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1N5HaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:44 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)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-nAi_Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-w9b6EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:44 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-aDHH51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28: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 03:28: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 03:28: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 03:28: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 03:28: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 03:28: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 03:28:47 INFO - Downloading blessings-1.6.tar.gz 03:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:47 INFO - Installing collected packages: blessings 03:28:47 INFO - Running setup.py install for blessings 03:28:47 INFO - Successfully installed blessings 03:28:47 INFO - Cleaning up... 03:28:47 INFO - Return code: 0 03:28:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:47 INFO - Reading from file tmpfile_stdout 03:28:47 INFO - Current package versions: 03:28:47 INFO - blessings == 1.6 03:28:47 INFO - blobuploader == 1.2.4 03:28:47 INFO - docopt == 0.6.1 03:28:47 INFO - functools32 == 3.2.3-2 03:28:47 INFO - jsonschema == 2.5.1 03:28:47 INFO - manifestparser == 1.1 03:28:47 INFO - mozInstall == 1.12 03:28:47 INFO - mozcrash == 1.0 03:28:47 INFO - mozdebug == 0.1 03:28:47 INFO - mozdevice == 0.48 03:28:47 INFO - mozfile == 1.2 03:28:47 INFO - mozhttpd == 0.7 03:28:47 INFO - mozinfo == 0.9 03:28:47 INFO - mozleak == 0.1 03:28:47 INFO - mozlog == 3.4 03:28:47 INFO - moznetwork == 0.27 03:28:47 INFO - mozprocess == 0.23 03:28:47 INFO - mozprofile == 0.28 03:28:47 INFO - mozrunner == 6.13 03:28:47 INFO - mozscreenshot == 0.1 03:28:47 INFO - mozsystemmonitor == 0.3 03:28:47 INFO - moztest == 0.7 03:28:47 INFO - mozversion == 1.4 03:28:47 INFO - psutil == 3.1.1 03:28:47 INFO - requests == 1.2.3 03:28:47 INFO - wsgiref == 0.1.2 03:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:47 INFO - 'HOME': '/Users/cltbld', 03:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:47 INFO - 'LOGNAME': 'cltbld', 03:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:47 INFO - 'MOZ_NO_REMOTE': '1', 03:28:47 INFO - 'NO_EM_RESTART': '1', 03:28:47 INFO - 'PAGER': '/bin/cat', 03:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:47 INFO - 'PWD': '/builds/slave/test', 03:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:47 INFO - 'SHELL': '/bin/bash', 03:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:47 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:47 INFO - 'USER': 'cltbld', 03:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:47 INFO - 'XPC_FLAGS': '0x0', 03:28:47 INFO - 'XPC_SERVICE_NAME': '0', 03:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 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)) 03:28:48 INFO - Cleaning up... 03:28:48 INFO - Return code: 0 03:28:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:28:48 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')]} 03:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:48 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')]} 03:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:48 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 0x109182490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc61dab110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, '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.JukKdidfBj/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.6k7TbkFdaY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:28:48 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 03:28:48 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 03:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:48 INFO - 'HOME': '/Users/cltbld', 03:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:48 INFO - 'LOGNAME': 'cltbld', 03:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:48 INFO - 'MOZ_NO_REMOTE': '1', 03:28:48 INFO - 'NO_EM_RESTART': '1', 03:28:48 INFO - 'PAGER': '/bin/cat', 03:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:48 INFO - 'PWD': '/builds/slave/test', 03:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:48 INFO - 'SHELL': '/bin/bash', 03:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:28:48 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:48 INFO - 'USER': 'cltbld', 03:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:48 INFO - 'XPC_FLAGS': '0x0', 03:28:48 INFO - 'XPC_SERVICE_NAME': '0', 03:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:28:48 INFO - Cleaning up... 03:28:48 INFO - Return code: 0 03:28:48 INFO - Running post-action listener: _resource_record_post_action 03:28:48 INFO - Running post-action listener: _start_resource_monitoring 03:28:48 INFO - Starting resource monitoring. 03:28:48 INFO - [mozharness: 2017-01-02 11:28:48.516699Z] Finished create-virtualenv step (success) 03:28:48 INFO - [mozharness: 2017-01-02 11:28:48.517679Z] Running install step. 03:28:48 INFO - Running pre-action listener: _resource_record_pre_action 03:28:48 INFO - Running main action method: install 03:28:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:48 INFO - Reading from file tmpfile_stdout 03:28:48 INFO - Detecting whether we're running mozinstall >=1.0... 03:28:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:28:48 INFO - Reading from file tmpfile_stdout 03:28:48 INFO - Output received: 03:28:48 INFO - Usage: mozinstall [options] installer 03:28:48 INFO - Options: 03:28:48 INFO - -h, --help show this help message and exit 03:28:48 INFO - -d DEST, --destination=DEST 03:28:48 INFO - Directory to install application into. [default: 03:28:48 INFO - "/builds/slave/test"] 03:28:48 INFO - --app=APP Application being installed. [default: firefox] 03:28:48 INFO - mkdir: /builds/slave/test/build/application 03:28:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:29:19 INFO - Reading from file tmpfile_stdout 03:29:19 INFO - Output received: 03:29:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:29:19 INFO - Running post-action listener: _resource_record_post_action 03:29:19 INFO - [mozharness: 2017-01-02 11:29:19.836919Z] Finished install step (success) 03:29:19 INFO - [mozharness: 2017-01-02 11:29:19.837044Z] Running run-tests step. 03:29:19 INFO - Running pre-action listener: _resource_record_pre_action 03:29:19 INFO - Running main action method: run_tests 03:29:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:19 INFO - 2017-01-02 03:29:19.850 screenresolution[1642:10175] starting screenresolution argv=screenresolution get 03:29:19 INFO - 2017-01-02 03:29:19.852 screenresolution[1642:10175] Display 0: 1600x1200x32@60 03:29:19 INFO - 2017-01-02 03:29:19.857 screenresolution[1643:10178] starting screenresolution argv=screenresolution list 03:29:19 INFO - Available Modes on Display 0 03:29:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:20 INFO - Intel Iris: 03:29:20 INFO - Chipset Model: Intel Iris 03:29:20 INFO - Type: GPU 03:29:20 INFO - Bus: Built-In 03:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:20 INFO - Vendor: Intel (0x8086) 03:29:20 INFO - Device ID: 0x0a2e 03:29:20 INFO - Revision ID: 0x0009 03:29:20 INFO - Displays: 03:29:20 INFO - Display: 03:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:20 INFO - Display Serial Number: 1600x1200 60 03:29:20 INFO - Main Display: Yes 03:29:20 INFO - Mirror: Off 03:29:20 INFO - Online: Yes 03:29:20 INFO - Rotation: Supported 03:29:20 INFO - Return code: 0 03:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:29:20 INFO - Python 2.7.3 03:29:20 INFO - Return code: 0 03:29:20 INFO - grabbing minidump binary from tooltool 03:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f96d30>, '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 0x108dfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f8eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:29:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:29:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:29:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:29:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:29:20 INFO - Return code: 0 03:29:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:29:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:29:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:29:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:29:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JukKdidfBj/Render', 03:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:20 INFO - 'HOME': '/Users/cltbld', 03:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:20 INFO - 'LOGNAME': 'cltbld', 03:29:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:29:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:20 INFO - 'MOZ_NO_REMOTE': '1', 03:29:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:29:20 INFO - 'NO_EM_RESTART': '1', 03:29:20 INFO - 'PAGER': '/bin/cat', 03:29:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:20 INFO - 'PWD': '/builds/slave/test', 03:29:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:20 INFO - 'SHELL': '/bin/bash', 03:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6k7TbkFdaY/Listeners', 03:29:20 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:20 INFO - 'USER': 'cltbld', 03:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:20 INFO - 'XPC_FLAGS': '0x0', 03:29:20 INFO - 'XPC_SERVICE_NAME': '0', 03:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:29:20 INFO - mozversion application_buildid: 20170102004004 03:29:20 INFO - mozversion application_changeset: 1458ff2725619c3db4b1dcb4f057f26a60208873 03:29:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:29:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:29:20 INFO - mozversion application_name: Firefox 03:29:20 INFO - mozversion application_remotingname: firefox-dev 03:29:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:29:20 INFO - mozversion application_vendor: Mozilla 03:29:20 INFO - mozversion application_version: 52.0a2 03:29:20 INFO - mozversion platform_buildid: 20170102004004 03:29:20 INFO - mozversion platform_changeset: 1458ff2725619c3db4b1dcb4f057f26a60208873 03:29:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:29:20 INFO - mozversion platform_version: 52.0a2 03:29:20 INFO - using testdate: 1483356560 03:29:20 INFO - actual date: 1483356560 03:29:20 INFO - starting webserver on 'localhost:49243' 03:29:20 INFO - SUITE-START | Running 2 tests 03:29:20 INFO - TEST-START | dromaeo_css 03:29:20 INFO - operating with platform_type : mac_ 03:29:20 INFO - Initialising browser for dromaeo_css test... 03:29:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpmm3gAG/profile http://localhost:49243/getInfo.html) 03:29:23 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 03:29:23 INFO - PROCESS | 1653 | colorDepth:24 03:29:23 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 03:29:23 INFO - PROCESS | 1653 | __metrics 03:29:23 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:29:23 INFO - PROCESS | 1653 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:29:24 INFO - TEST-INFO | 1653: exit 0 03:29:24 INFO - Browser initialized. 03:29:24 INFO - Running cycle 1/1 for dromaeo_css test... 03:29:24 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpmm3gAG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:36 INFO - PROCESS | 1657 | RSS: Main: 222965760 03:29:36 INFO - PROCESS | 1657 | 03:30:27 INFO - PROCESS | 1657 | 1483356627898 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:32:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 03:32:42 INFO - PROCESS | 1657 | RSS: Main: 328953856 03:32:42 INFO - PROCESS | 1657 | 03:35:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 03:35:46 INFO - PROCESS | 1657 | RSS: Main: 345976832 03:35:46 INFO - PROCESS | 1657 | 03:38:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 03:38:49 INFO - PROCESS | 1657 | RSS: Main: 320126976 03:38:49 INFO - PROCESS | 1657 | 03:41:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 03:41:52 INFO - PROCESS | 1657 | RSS: Main: 325181440 03:41:52 INFO - PROCESS | 1657 | 03:44:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 03:44:56 INFO - PROCESS | 1657 | RSS: Main: 313835520 03:44:56 INFO - PROCESS | 1657 | 03:47:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 03:48:00 INFO - PROCESS | 1657 | RSS: Main: 381042688 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | __start_tp_report 03:48:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 03:48:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 03:48:00 INFO - PROCESS | 1657 | |i|pagename|runs| 03:48:00 INFO - PROCESS | 1657 | |0;dojo.html;3345.7825241359324;3355;3355.6308806031348;3365.7812242204254;3401.676840700134;5667.603267771257;5742.224799652056;5764.654120752758;5781.942180578193;5793.884122317551;5675.8581035474135;5693.28833895763;5771.942280577193;5778.653280803144;5797.217375654296;5187.714675692835;5237.47625237477;5262.552683021929;5285.550728188108;5313.8937221255555;5179.326687530615;5180.326557547513;5235.319408476893;5341.172118321666;5352.545033672123;5228.790848366079;5287;5293;5302.628815982883;5315.14957606783;5293.708846013467;5354.946450535484;5361.329833770778;5366.812161574326;5380.677159370451;5044.445111037783;5167.560757335594;5245.344331958505;5245.659032162897;5291.708956007418;5610.382857885629;5638.379778224392;5643.633163844337;5659.943400565983;5672.574556908207;6257.092721555349;6296.9370306296805;6317.557770956035;6321.936780632181;6327.746890124389;6164.445199931983;6337.936620633781;6353.110564520971;6362.809115726489;6384.425401713822;5692;5773.39379365169;5809.7966571170655;5870.4716575507555;5878.147668588031;6358.268799088071;6396.904046439307;6406.519511036691;6410.45511131559;6411;5986.5809393342915;6186.783462578788;6194.163787888668;6207.6585787782105;6213.937860621426;6177.505799536116;6182.474489668429;6212.502999760009;6272.843178920563;6285.560010799291;6248.187735594434;6254.405831446005;6258.843528911813;6262.499000079983;6266.874662506815;6245.968770156211;6286.402791734778;6291.559590828598;6295.433410993032;6319.33646967071;4863.829765958175;4913.606911447076;4913.87715307113;4917.704937703749;4942.135126352874;4403.295472724349;4414.713043652152;4428.047969686469;4445;4475.686701930834;5751.884962300731;5759.049756790167;5768.769249229984;5784.537237021028;5803.88392232153;6123.5876691309595;6141.447269745744;6151.507879369639;6316.242050953915;6325.3674632537295;5692.857678558069;5752.539796816244;5766.798162064308;5824.213731146241;5827.125931110368;5374.865628359323;5423.647462914898;5425.972870135703;5429.4027656957705;5437.537809286255;6386.010168423994;6387.137736405526;6397.712102955378;6412;6416.48668106541;4727.669063165683;6112.533877023439;6179.134921111138;6293.37066293367;6309.558330916791;6372.235331760033;6386.9680651596445;6388.009858471944;6415.518836087405;6418.4865210783955;6210.720517576629;6217.906731398942;6274.435300822948;6286.811395658137;6301.117843501822;6109.61722939883;6130.63216207029;6187.010078387437;6193.4425901669065;6196.969015154896;6055.424734650202;6109.11417844419;6114.785982490592;6114.816555503342;6126;6121.4490695837585;6128.050152226412;6140.263168419819;6144.2319710036245;6158.630482171084;5743.56923230751;5848.239728835224;5896.14505896634;5901.114832775118;5928.851778705567;6168.629882207082;6170.629762214281;6174.38256174379;6188.690565471798;6189.96905015472;5960.910586341121;5991.011483105239;6005.099235114768;6007;6052.152698622284 03:48:00 INFO - PROCESS | 1657 | |1;ext.html;461.789885602051;478.98084076636934;504.13540777566465;515.58495411194;553.9639923404981;29911.252218694517;31482;38119.80940095299;39106.608933910655;39114.41328380069;41348.79325603375;41758.37362439558;41779.95550111249;41847.37228941561;41881.79059104708;9384.624615015407;9484.573194206243;9550.140487356137;9566.521673916312;9582.760430989247;8536.359773017017;8740.34447416443;9465.526723663823;11280.717982050473;11541.480633371519;11321.377324247163;11362.545498180063;11372.260803047817;11401.087912966985;11446.370449625267;11422.42887855608;11490.138239631977;11521.193516453852;11524.769504609862;11525.366104864188;8500;8722.738317850411;11034.282771619819;11354.034907032912;11376.772464550662;39392.60607393947;39649.20701585952;39695.40456893149;39724.60275397267;39758.80120599378;3204.9198770030703;3221.548983142362;3270.4767237242163;3481.860725570974;3507.3862074136923;29065.41869162626;30161.396772064654;30259.546106808415;30788.538171927215;30897.073087807403;2678.7187345328653;3141.748660107186;3187.2828613561765;3241.8865339712997;3269.264415506516;475214;480621;481253;481744;486493.56753216364;59825.80348393095;59912.40087599155;61367.693161533905;61608;61689;61986;62118;63763.36236637667;63841;64107.03839442412;15460;15751;16126.75809862853;16127.193640317937;16157.272922718506;11166;12215.69460763488;12283.754324913452;12292.692682683004;12304;15340.769888451681;15591.922040389725;17731.556711082067;17944.641107178046;18128.546786330182;6696.430803381767;6699.263081061079;6771.221309549464;6800.4219641330055;6824.93175068243;822.596927505518;879.7668617816283;942.4863449420083;1055.1980494823836;1099.433791597328;28348.433031339257;29215.707842921725;29382.55926161109;29909.252268693457;30008.799648013835;1028.1671845804922;1047.7904419116207;1052.3002203534718;1064.622059168984;1126.9042131418757;14914.776278355834;15149.545513634608;15312.770308445382;15338.616534586501;15561.14413707257;629760;629941.7005829975;630018;635314;635476;56998.71500642553;57009.714951424976;57029.71485142548;57066.7146664264;57136;1333.8466076401144;1371.5748118083427;1384.1487485196733;1385.4873696732272;1388.7500249954903;28691;28853.990110346036;29146.417071658023;29913;30101.849490752407;50341.74829125831;50861.72845678888;50994.49005510046;51078.23382649189;51086;469226.3077369183;471204.28795712977;473343;473597;474249;23326.883365583064;26778;27189.184324470298;27330.453390932467;40544.391834123104;37213.25573488461;38155.855324340315;41473.585264146976;45331.773341133085;45548.86127846831;36128.63871361253;36662.63337366699;37908.1045947707;38145;39529.604703953744;37349.69276075324;37909.62090379061;38066.429003564415;38234.8088259568;39361.21277574375;31116;37208.44187337138;38017.2396552073;38144;38163.236735265695;32655.510167347034;33918.491222632176;34424.82787586046;34773.652263478056;34810;25252;25278.115265965604;25287;25596.360090997874;25770 03:48:00 INFO - PROCESS | 1657 | |2;jquery.html;330.45639922327763;371.867986864663;386.30272358393097;393.8129388540442;406.39041437843235;18811.435656930298;19186;20104.597908041847;20242.696359554582;20539.486512837186;11702.882971170307;11777.175597708163;12197.207181533195;12248.571300004502;12254.264744115337;5194.324737784093;5502.477264659871;5513.531349835249;5522.91715624266;5525.0883604205255;3824.3116239076953;4033.9394909076386;4041.636252737253;5075.441701412841;5425.945740542584;4122.278601244785;6648.434883034948;6686.4985126115735;6705.161854768154;6716.899246511306;6046.727897244635;6103.328633850273;6271.34150914152;6693.096431981693;6701.564398314094;5913.46778789911;5914.822555323347;5920.585559010871;5928.644281343133;5947.375525569796;12908.225506469642;13139.737205255937;13230.073894827367;13239.735205295936;13240.735185296337;2134.96603237236;2151.34384012876;2247.3033359658557;2254.921077762287;2265.5468906218657;10426.843597346045;10680.839787403194;11050.226484146113;13546.59360219195;15423.922880385526;1799.4151900632216;1846.7876194237701;1847.316492897623;1924.9422517324363;1987.21505005522;92676.53661731648;121770;122523;126359;126637.36681316627;9552.952235238918;11226.203807390779;11706.473208705629;11711.355875426763;11742.70643233909;14675.706485870223;14731.705365892622;14753;14757.557273281818;14761.704765904622;8244.340452763765;8468.44955077918;8516.74449766508;8665.913340866511;8671.4363566368;6766.729330826712;8339.999200096026;8345.874811877828;8502.574871256413;8520;4095.52901416335;4218.7679677618025;4555.362249285103;4559.794809233593;4639.072185562901;1641.958951026234;1750.212404418017;1894.895780732073;1926.6628340040436;1935.438722770409;671.1007250284642;707.8568112498278;880.9119088091139;883.911608839111;938.8075444533854;18437.631247375244;18442.723359149622;20007.299744508873;21098.894505527373;21642;869.6086760957468;897.7261935109724;908.3596064774318;922.4096578189964;928.9442633442015;8151.673933042612;10863.402512861869;11153;11311.208215425006;11428.828567571492;596931;631445;639352;641632;656636;70635.64682176556;114956.24622058295;124481;134925;142387;866.8786369908217;971.8056388722284;987.5802783816993;1003.1072345612411;1008.541113793237;13219;17631;18889.105476501252;19725.785489647486;19837.900810496434;53429.732851335495;54047.91904161973;54888.90222195454;55640.72179639076;56342.71828640831;69431.30568694249;73765.26234737798;74377.51244975178;75923;76274;6774.254831968381;7195.244499327495;7407.481476296607;7465.701371945279;8282.50304981703;12288.75422491563;12548.372581371077;12746.171498852917;12947.223166610034;12947.66941950458;10311.535980880728;10628.36229826213;10754.83867742;10821.9458902705;11105.278156919774;10713.732156696145;10721.35671859691;10793.29843560166;11170.944145279222;11191.16066295015;9549.856752148862;10490.213234007328;10909.945450272699;11117.722056948642;11477;6559.245686746085;7959.442839001446;7962.163972782774;8422.705205317785;8505.277051450696;8210.507369557845;10165.390076595428;10805.783884322112;11109.777804444027;11148.498317575566 03:48:00 INFO - PROCESS | 1657 | |3;mootools.html;1458.854114588541;1475.159159279211;1624.3502598960415;1758.3054693396116;1815.6731788278114;7901.920980790191;8415.368847336444;8418.200270974263;8429.241368276855;8442.746717598475;7392;7579.507332023414;7593.278638529331;7715.768526944186;8214.876776848352;2090.5714328562685;2118.03629348646;2126.13891373993;2250.3698964290024;2330.1145564685394;2353.223436266028;2363.4918492524102;2364.834461587681;2369.39580406996;2370.5614461324626;2290.885455727207;2327.197116994634;2347.190219374313;2354.3996280948336;2354.8704821234824;2308.0652335804025;2315.9305220843403;2338.1582630253097;2342.875419798489;2345.5543446745232;2318.4783423729696;2328.650702394654;2331.813454923602;2362.090595120874;2363.7399886012527;8230.176982301782;8246.546439945801;8294.004719433646;8324.833503329899;8334.20825021622;5061.139606266944;5227.738613069331;5228.947710522884;5236.109861323546;5244.134717771602;3492.877749278763;3500.6149323574386;3510.4207805712026;3527.0476971217886;3592.4611308303965;1057.8466122412285;1058.7406085509099;1080.5353697909895;1091.7434402915308;1101.8677758669012;10771.999999999922;10854.077403420719;10855.077318427942;10865.83701244482;10924.726881827859;4467.7319360838455;4611.446626404853;4727;4732.02993386355;4763.880902977384;9182.403143795633;9385.779848619635;9388.90611093894;9389.577469013911;9405.717828465156;5102.770375333119;5107.387113546398;5114;5173.586113110942;5224.9738751306;4566.292224705176;4715.104130215248;4771.5228477152;4792.257200133985;4793.616510679138;3590.79708297718;3679.2273622539033;3705.6850167735506;3759.285735710206;3790.3556395412847;1249.6938250128776;1260.2249616485979;1260.5462033667873;1274.2927675140386;1275.489804078376;850.3154960257052;868.6829307302789;876.2945828607905;890.9109089091039;900.3247564326755;5538.418466061086;5631.802886898939;5657.745401456944;5699.914501282484;5774.335951365647;794.8251384695358;806.842181469593;912.2428384440898;941.6751220828803;981.8919918808925;3738.9065273368383;3744.2885851688093;3752.5121734174927;3776.4712940188397;3828.9617103828605;9548.42709437436;9616.182624476858;10041.497925103715;10123.341982771244;10132.189424846141;9899.30704850668;10075.546600402999;10081.445520496443;10099.192064634812;10104.09063184317;7528.623568821537;7822.960885195537;7845.0978137513775;7848.215178482106;7861.921380786233;5534.806281780118;5587.804426845041;5626.774929002794;5749.8275051748515;5801;9276.907230927603;9344.486053267136;9418.858717119385;9448.385854919408;9459.527023648927;8971.641134354553;9287.349885507949;9301.906980930375;9339.252859771066;9513.191378732608;7025.929740702528;7190.42476601881;7202.89195662055;7207.531510451804;7213.422926165789;6984.2317345093015;7199;7208.026916366223;7716.922830771845;7807.843843123219;6841.110655614738;6851.897221541781;7078.964605176941;7112.928870711226;7134.678939447841;6723.327667233238;6929.376356127973;7015.894761578478;7056.1179852518435;7088.184858741104;6471.935280647322;6693.832654183684;6705.430038446787;6708.798736037927;6718.529702920749;4556.587457888066;4559.521250268674;4648.558386953234;4699.083678682624;4794.520547945317;4731.195696731495;4790.808367665254;4798.95201047985;4838.201696720002;4986.825461108989 03:48:00 INFO - PROCESS | 1657 | |4;prototype.html;296.47079962267344;300.17002986741664;310.9238236632024;314.8048210109731;316.51257064121256;4239.872803815891;4522.253828118365;4564.634829213664;5204.791808327667;5336.893262134755;4249.851255206061;5049.495050494948;5091.199840087956;5242.868928276795;5305.585958388924;4026.21488809683;4038.9394159087637;4054.209429161315;4066.654334381581;4073.2464494068545;3861.4014827701753;3886.7950935210033;4035.4350390945147;4050.9392359114636;4073.40935564343;3156.905292841207;3892.9805350973206;4044.3933409988445;4053.148838743867;4053.885181575061;3970.5110583531177;3985.262726395626;4007.5391329997133;4024.8188831502653;4037.9905023744063;3476.461148521983;3479.5476588043643;3479.7564170508076;3485.8779942701885;3494.6155922848466;4242.109157077018;4993.825216117419;5106.336176297094;5162.5611822994715;5196.428392876781;3614.8011859347725;4247.978760106211;4520.932186017212;4610.953890461086;4630.907381852344;4983.04526091321;5224.4775522447835;5225.159507761792;5561.805336813232;5562.221289019541;3070.0175943698027;3188.2507610711464;3283.589551306087;3816.996077059538;3843.0200298923874;5352.68859129508;5510.476504732044;5760.745178973866;5836.533077353844;5862.101702367921;3082.7533797296164;3109.5589961879814;3298.67013298673;3920.627540383659;3929.901752456154;3996.820143093568;3997.5003124609425;4003.53959294679;4005.7181701855125;4005.759654420744;4104.835806567704;4472.530384309665;4493.28107502798;4549.363089167519;4556.883563526957;4903;5473.9452605474235;5491.423400542965;5493.258410114663;5616.241807356037;1175.9588414405455;1176.405915012913;1202.5430336472084;1226.6994586326407;1263.6082814327588;802.6628815897337;822.6668199379261;836.8200836820176;859.4155973937783;866.9783255418539;4691;4838.467768545457;4920.720612640667;4935.703857768545;4940.827071052496;4880.755962201875;5347.3048503695045;5383.515483606494;5385.734352427176;5392.409239274423;4148.709590328708;4561.589456948891;4720.527947205321;4854.538818812207;4909.435414927258;2726.4410795786816;2739.986300068527;2741.7121202273875;2774.1122840690787;2788.316862368692;4845.563899249014;4880.267959805987;5296.622878051658;5454.636340914772;5500.697461639568;5100.744962751847;5367.919481207785;5371.597130215249;5395.406505284415;5446.618736688394;5476.58925580583;5532.5297349724915;5575.804846830341;5595.468430499096;5618.269624948731;4766.380370551806;5010.248462730619;5020.439575816287;5383.757730902197;5417.6478528895495;4746.92879606813;5018.623603229699;5232.744141406111;5360.758765855624;5690.94717477268;4984;5302.019126461617;5893;5933.199018132497;5987.461128498282;3827.9617203827606;3853.5761066282125;3872.1094148345474;3919.2749341371937;3944.3886197638262;5426.728663566727;5706;5778.306603207642;5802;5825.912611310749;4908.833103668684;5275.419703832652;5434.266374039454;5447.439703339424;5636.323641163087;5124;5574.08027675429;5608.327000759935;5637.210790489253;5672.355017045011;4357.213542447581;5081.466446023114;5391.272178256042;5457.590680698884;5555.166864899012;4406.405135306821;4603.516630753723;5432.854022661106;5575.330960284792;5593.776248950159;4679.461861885927;5040.49595040493;5424.593155513431;5557.5553955682635;5566.276384070045 03:48:00 INFO - PROCESS | 1657 | |5;yui.html;661.9702113404898;711.9430445564359;732.2969948849104;750.0699133074988;773.4663082473095;13666.931665341672;14418.711625767504;14469.565913022612;14483.710325793478;14493;12802;12885.033622478304;12896.290704011299;12897.226166429997;12912.741745165114;627.2097157581435;648.6237981970446;769.8267889724823;788.9368850491946;800.1678254615193;783.6316931042401;790.8220650353653;794.6265255329986;795.1174196641723;796.0566728426833;835.0688981785296;837.5602808525542;838.4675730910885;839.3998291221775;840.3487297344576;651.5992664511339;679.2765704524666;828.5443006346545;828.6726742936537;830.5598033042437;502.63307785316925;503.73553884210713;504.095149207174;600.6936462404161;624.622103627308;13676.863231367659;13749.518766843212;13769.586912392644;13782.517611883637;13791;12505.687357816036;12717.809232861515;12751.362431878462;12802.423890924838;12809.10336276461;10473.895261047368;10748.086412654933;10931.83602245959;12054.698632534257;12100.258428872203;4119.567445418248;4146.390966649686;4219.91560168798;4253.255680255974;4295.463067116611;79946.20053799446;79989.60005199937;80518.19481805165;81053;82261.58869205676;8427.620757065946;9791.873934497482;9937.354071985297;9998.500074996222;10160.69517914449;18720.438386848415;18724.344647937258;18729.906350468435;18744.437666870017;18769.624607508045;6861.279565645636;7034.120734908137;7283.417326613858;7384.630768461556;7427.962860185665;4133.81397837098;4222.59885310895;4234.682398820039;4243.490781106287;4263.55232700562;3172.476541370648;3298.1589694628196;3310.9901480048816;3337.4159522084024;3340.682635149657;666.1040899989506;676.6496430995048;680.7072958627739;690.8031211104811;694.9027136200936;548.6762809942153;553.6124712701045;553.7120697237414;605.7788907048896;659.304433822322;8484.703035393855;8979.640814367483;9014.63941442348;9261.351705380694;9522.761930951643;529.1612793721916;584.3017593975159;609.7439075588263;652.1587152573222;658.256170527301;3833.9808300958316;4123.2165888481095;4374.650027997753;4383.9342409863875;4407.096545208225;85526;88782.55608721916;91807.5409622961;99973;115872;57111.428885711444;61957;76872.23127768762;80724.59637701773;85697.57151214204;76356.61821690967;82125;82192;82301.58849205716;83887.58056209802;9051.54742262871;9401.34190606651;9454.37840482963;9486.288528360263;9513.90486095158;52350.476495236086;53249.20126198188;53253.73373133109;53473.73263133815;53746.92506149777;61596.38403615906;62235.37764622296;66419.33951651247;67734.32265677478;69369.65315173392;11969.16215864881;11993.580224692094;12093.213941093802;12294.569690060805;12398.25610463375;12984.870151298745;13905.582832515041;13999.790003149757;14634.414623414945;14782.113073215642;11944.069997065244;12614.243145411305;12638.684032898884;13054.05029873575;13064;12725.872741272839;12977.675558111123;13424.194548326741;13454.93272533631;13737.862621373659;12099.879001209874;12338;12757;12771.169873958162;12877.613671589866;9324;10193.216187167365;10595.735106622396;11038.392888391216;11164.162687798285;10526.421046842497;10705.678829635124;10937.507812148631;10950.72623184395;10979.945100274766 03:48:00 INFO - PROCESS | 1657 | __end_tp_report 03:48:00 INFO - PROCESS | 1657 | __start_cc_report 03:48:00 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1323 03:48:00 INFO - PROCESS | 1657 | __end_cc_report 03:48:00 INFO - PROCESS | 1657 | __startTimestamp1483357680047__endTimestamp 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | ------- Summary: start ------- 03:48:00 INFO - PROCESS | 1657 | Number of tests: 6 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5774.55 Median:5986.58 stddev:650.22 (10.9%) stddev-sans-first:623.67 03:48:00 INFO - PROCESS | 1657 | Values: 3345.8 3355.0 3355.6 3365.8 3401.7 5667.6 5742.2 5764.7 5781.9 5793.9 5675.9 5693.3 5771.9 5778.7 5797.2 5187.7 5237.5 5262.6 5285.6 5313.9 5179.3 5180.3 5235.3 5341.2 5352.5 5228.8 5287.0 5293.0 5302.6 5315.1 5293.7 5354.9 5361.3 5366.8 5380.7 5044.4 5167.6 5245.3 5245.7 5291.7 5610.4 5638.4 5643.6 5659.9 5672.6 6257.1 6296.9 6317.6 6321.9 6327.7 6164.4 6337.9 6353.1 6362.8 6384.4 5692.0 5773.4 5809.8 5870.5 5878.1 6358.3 6396.9 6406.5 6410.5 6411.0 5986.6 6186.8 6194.2 6207.7 6213.9 6177.5 6182.5 6212.5 6272.8 6285.6 6248.2 6254.4 6258.8 6262.5 6266.9 6246.0 6286.4 6291.6 6295.4 6319.3 4863.8 4913.6 4913.9 4917.7 4942.1 4403.3 4414.7 4428.0 4445.0 4475.7 5751.9 5759.0 5768.8 5784.5 5803.9 6123.6 6141.4 6151.5 6316.2 6325.4 5692.9 5752.5 5766.8 5824.2 5827.1 5374.9 5423.6 5426.0 5429.4 5437.5 6386.0 6387.1 6397.7 6412.0 6416.5 4727.7 6112.5 6179.1 6293.4 6309.6 6372.2 6387.0 6388.0 6415.5 6418.5 6210.7 6217.9 6274.4 6286.8 6301.1 6109.6 6130.6 6187.0 6193.4 6197.0 6055.4 6109.1 6114.8 6114.8 6126.0 6121.4 6128.1 6140.3 6144.2 6158.6 5743.6 5848.2 5896.1 5901.1 5928.9 6168.6 6170.6 6174.4 6188.7 6190.0 5960.9 5991.0 6005.1 6007.0 6052.2 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:66544.97 Median:36935.54 stddev:142370.49 (385.5%) stddev-sans-first:142683.42 03:48:00 INFO - PROCESS | 1657 | Values: 461.8 479.0 504.1 515.6 554.0 29911.3 31482.0 38119.8 39106.6 39114.4 41348.8 41758.4 41780.0 41847.4 41881.8 9384.6 9484.6 9550.1 9566.5 9582.8 8536.4 8740.3 9465.5 11280.7 11541.5 11321.4 11362.5 11372.3 11401.1 11446.4 11422.4 11490.1 11521.2 11524.8 11525.4 8500.0 8722.7 11034.3 11354.0 11376.8 39392.6 39649.2 39695.4 39724.6 39758.8 3204.9 3221.5 3270.5 3481.9 3507.4 29065.4 30161.4 30259.5 30788.5 30897.1 2678.7 3141.7 3187.3 3241.9 3269.3 475214.0 480621.0 481253.0 481744.0 486493.6 59825.8 59912.4 61367.7 61608.0 61689.0 61986.0 62118.0 63763.4 63841.0 64107.0 15460.0 15751.0 16126.8 16127.2 16157.3 11166.0 12215.7 12283.8 12292.7 12304.0 15340.8 15591.9 17731.6 17944.6 18128.5 6696.4 6699.3 6771.2 6800.4 6824.9 822.6 879.8 942.5 1055.2 1099.4 28348.4 29215.7 29382.6 29909.3 30008.8 1028.2 1047.8 1052.3 1064.6 1126.9 14914.8 15149.5 15312.8 15338.6 15561.1 629760.0 629941.7 630018.0 635314.0 635476.0 56998.7 57009.7 57029.7 57066.7 57136.0 1333.8 1371.6 1384.1 1385.5 1388.8 28691.0 28854.0 29146.4 29913.0 30101.8 50341.7 50861.7 50994.5 51078.2 51086.0 469226.3 471204.3 473343.0 473597.0 474249.0 23326.9 26778.0 27189.2 27330.5 40544.4 37213.3 38155.9 41473.6 45331.8 45548.9 36128.6 36662.6 37908.1 38145.0 39529.6 37349.7 37909.6 38066.4 38234.8 39361.2 31116.0 37208.4 38017.2 38144.0 38163.2 32655.5 33918.5 34424.8 34773.7 34810.0 25252.0 25278.1 25287.0 25596.4 25770.0 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34945.13 Median:1298.00 stddev:105372.47 (8118.1%) stddev-sans-first:105636.01 03:48:00 INFO - PROCESS | 1657 | Values: 330.5 371.9 386.3 393.8 406.4 18811.4 19186.0 20104.6 20242.7 20539.5 11702.9 11777.2 12197.2 12248.6 12254.3 5194.3 5502.5 5513.5 5522.9 5525.1 3824.3 4033.9 4041.6 5075.4 5425.9 4122.3 6648.4 6686.5 6705.2 6716.9 6046.7 6103.3 6271.3 6693.1 6701.6 5913.5 5914.8 5920.6 5928.6 5947.4 12908.2 13139.7 13230.1 13239.7 13240.7 2135.0 2151.3 2247.3 2254.9 2265.5 10426.8 10680.8 11050.2 13546.6 15423.9 1799.4 1846.8 1847.3 1924.9 1987.2 92676.5 121770.0 122523.0 126359.0 126637.4 9553.0 11226.2 11706.5 11711.4 11742.7 14675.7 14731.7 14753.0 14757.6 14761.7 8244.3 8468.4 8516.7 8665.9 8671.4 6766.7 8340.0 8345.9 8502.6 8520.0 4095.5 4218.8 4555.4 4559.8 4639.1 1642.0 1750.2 1894.9 1926.7 1935.4 671.1 707.9 880.9 883.9 938.8 18437.6 18442.7 20007.3 21098.9 21642.0 869.6 897.7 908.4 922.4 928.9 8151.7 10863.4 11153.0 11311.2 11428.8 596931.0 631445.0 639352.0 641632.0 656636.0 70635.6 114956.2 124481.0 134925.0 142387.0 866.9 971.8 987.6 1003.1 1008.5 13219.0 17631.0 18889.1 19725.8 19837.9 53429.7 54047.9 54888.9 55640.7 56342.7 69431.3 73765.3 74377.5 75923.0 76274.0 6774.3 7195.2 7407.5 7465.7 8282.5 12288.8 12548.4 12746.2 12947.2 12947.7 10311.5 10628.4 10754.8 10821.9 11105.3 10713.7 10721.4 10793.3 11170.9 11191.2 9549.9 10490.2 10909.9 11117.7 11477.0 6559.2 7959.4 7962.2 8422.7 8505.3 8210.5 10165.4 10805.8 11109.8 11148.5 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5414.03 Median:5143.79 stddev:2942.24 (57.2%) stddev-sans-first:2935.48 03:48:00 INFO - PROCESS | 1657 | Values: 1458.9 1475.2 1624.4 1758.3 1815.7 7901.9 8415.4 8418.2 8429.2 8442.7 7392.0 7579.5 7593.3 7715.8 8214.9 2090.6 2118.0 2126.1 2250.4 2330.1 2353.2 2363.5 2364.8 2369.4 2370.6 2290.9 2327.2 2347.2 2354.4 2354.9 2308.1 2315.9 2338.2 2342.9 2345.6 2318.5 2328.7 2331.8 2362.1 2363.7 8230.2 8246.5 8294.0 8324.8 8334.2 5061.1 5227.7 5228.9 5236.1 5244.1 3492.9 3500.6 3510.4 3527.0 3592.5 1057.8 1058.7 1080.5 1091.7 1101.9 10772.0 10854.1 10855.1 10865.8 10924.7 4467.7 4611.4 4727.0 4732.0 4763.9 9182.4 9385.8 9388.9 9389.6 9405.7 5102.8 5107.4 5114.0 5173.6 5225.0 4566.3 4715.1 4771.5 4792.3 4793.6 3590.8 3679.2 3705.7 3759.3 3790.4 1249.7 1260.2 1260.5 1274.3 1275.5 850.3 868.7 876.3 890.9 900.3 5538.4 5631.8 5657.7 5699.9 5774.3 794.8 806.8 912.2 941.7 981.9 3738.9 3744.3 3752.5 3776.5 3829.0 9548.4 9616.2 10041.5 10123.3 10132.2 9899.3 10075.5 10081.4 10099.2 10104.1 7528.6 7823.0 7845.1 7848.2 7861.9 5534.8 5587.8 5626.8 5749.8 5801.0 9276.9 9344.5 9418.9 9448.4 9459.5 8971.6 9287.3 9301.9 9339.3 9513.2 7025.9 7190.4 7202.9 7207.5 7213.4 6984.2 7199.0 7208.0 7716.9 7807.8 6841.1 6851.9 7079.0 7112.9 7134.7 6723.3 6929.4 7015.9 7056.1 7088.2 6471.9 6693.8 6705.4 6708.8 6718.5 4556.6 4559.5 4648.6 4699.1 4794.5 4731.2 4790.8 4799.0 4838.2 4986.8 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4389.95 Median:4905.92 stddev:1347.49 (27.5%) stddev-sans-first:1315.78 03:48:00 INFO - PROCESS | 1657 | Values: 296.5 300.2 310.9 314.8 316.5 4239.9 4522.3 4564.6 5204.8 5336.9 4249.9 5049.5 5091.2 5242.9 5305.6 4026.2 4038.9 4054.2 4066.7 4073.2 3861.4 3886.8 4035.4 4050.9 4073.4 3156.9 3893.0 4044.4 4053.1 4053.9 3970.5 3985.3 4007.5 4024.8 4038.0 3476.5 3479.5 3479.8 3485.9 3494.6 4242.1 4993.8 5106.3 5162.6 5196.4 3614.8 4248.0 4520.9 4611.0 4630.9 4983.0 5224.5 5225.2 5561.8 5562.2 3070.0 3188.3 3283.6 3817.0 3843.0 5352.7 5510.5 5760.7 5836.5 5862.1 3082.8 3109.6 3298.7 3920.6 3929.9 3996.8 3997.5 4003.5 4005.7 4005.8 4104.8 4472.5 4493.3 4549.4 4556.9 4903.0 5473.9 5491.4 5493.3 5616.2 1176.0 1176.4 1202.5 1226.7 1263.6 802.7 822.7 836.8 859.4 867.0 4691.0 4838.5 4920.7 4935.7 4940.8 4880.8 5347.3 5383.5 5385.7 5392.4 4148.7 4561.6 4720.5 4854.5 4909.4 2726.4 2740.0 2741.7 2774.1 2788.3 4845.6 4880.3 5296.6 5454.6 5500.7 5100.7 5367.9 5371.6 5395.4 5446.6 5476.6 5532.5 5575.8 5595.5 5618.3 4766.4 5010.2 5020.4 5383.8 5417.6 4746.9 5018.6 5232.7 5360.8 5690.9 4984.0 5302.0 5893.0 5933.2 5987.5 3828.0 3853.6 3872.1 3919.3 3944.4 5426.7 5706.0 5778.3 5802.0 5825.9 4908.8 5275.4 5434.3 5447.4 5636.3 5124.0 5574.1 5608.3 5637.2 5672.4 4357.2 5081.5 5391.3 5457.6 5555.2 4406.4 4603.5 5432.9 5575.3 5593.8 4679.5 5040.5 5424.6 5557.6 5566.3 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:18800.78 Median:26889.18 stddev:26419.61 (98.3%) stddev-sans-first:26458.62 03:48:00 INFO - PROCESS | 1657 | Values: 662.0 711.9 732.3 750.1 773.5 13666.9 14418.7 14469.6 14483.7 14493.0 12802.0 12885.0 12896.3 12897.2 12912.7 627.2 648.6 769.8 788.9 800.2 783.6 790.8 794.6 795.1 796.1 835.1 837.6 838.5 839.4 840.3 651.6 679.3 828.5 828.7 830.6 502.6 503.7 504.1 600.7 624.6 13676.9 13749.5 13769.6 13782.5 13791.0 12505.7 12717.8 12751.4 12802.4 12809.1 10473.9 10748.1 10931.8 12054.7 12100.3 4119.6 4146.4 4219.9 4253.3 4295.5 79946.2 79989.6 80518.2 81053.0 82261.6 8427.6 9791.9 9937.4 9998.5 10160.7 18720.4 18724.3 18729.9 18744.4 18769.6 6861.3 7034.1 7283.4 7384.6 7428.0 4133.8 4222.6 4234.7 4243.5 4263.6 3172.5 3298.2 3311.0 3337.4 3340.7 666.1 676.6 680.7 690.8 694.9 548.7 553.6 553.7 605.8 659.3 8484.7 8979.6 9014.6 9261.4 9522.8 529.2 584.3 609.7 652.2 658.3 3834.0 4123.2 4374.7 4383.9 4407.1 85526.0 88782.6 91807.5 99973.0 115872.0 57111.4 61957.0 76872.2 80724.6 85697.6 76356.6 82125.0 82192.0 82301.6 83887.6 9051.5 9401.3 9454.4 9486.3 9513.9 52350.5 53249.2 53253.7 53473.7 53746.9 61596.4 62235.4 66419.3 67734.3 69369.7 11969.2 11993.6 12093.2 12294.6 12398.3 12984.9 13905.6 13999.8 14634.4 14782.1 11944.1 12614.2 12638.7 13054.1 13064.0 12725.9 12977.7 13424.2 13454.9 13737.9 12099.9 12338.0 12757.0 12771.2 12877.6 9324.0 10193.2 10595.7 11038.4 11164.2 10526.4 10705.7 10937.5 10950.7 10979.9 03:48:00 INFO - PROCESS | 1657 | -------- Summary: end -------- 03:48:00 INFO - PROCESS | 1657 | 03:48:00 INFO - TEST-INFO | 1657: exit 0 03:48:00 INFO - TEST-OK | dromaeo_css | took 1120259ms 03:48:00 INFO - TEST-START | kraken 03:48:00 INFO - operating with platform_type : mac_ 03:48:00 INFO - Initialising browser for kraken test... 03:48:00 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpm22Yi9/profile http://localhost:49243/getInfo.html) 03:48:03 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 03:48:03 INFO - PROCESS | 1685 | colorDepth:24 03:48:03 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 03:48:03 INFO - PROCESS | 1685 | __metrics 03:48:03 INFO - PROCESS | 1685 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:48:03 INFO - PROCESS | 1685 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:48:04 INFO - TEST-INFO | 1685: exit 0 03:48:04 INFO - Browser initialized. 03:48:04 INFO - Running cycle 1/1 for kraken test... 03:48:04 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpm22Yi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:16 INFO - PROCESS | 1687 | RSS: Main: 222851072 03:48:16 INFO - PROCESS | 1687 | 03:48:19 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 03:48:20 INFO - PROCESS | 1687 | RSS: Main: 272498688 03:48:20 INFO - PROCESS | 1687 | 03:48:23 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 03:48:23 INFO - PROCESS | 1687 | RSS: Main: 283164672 03:48:23 INFO - PROCESS | 1687 | 03:48:27 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 03:48:27 INFO - PROCESS | 1687 | RSS: Main: 381063168 03:48:27 INFO - PROCESS | 1687 | 03:48:29 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 03:48:30 INFO - PROCESS | 1687 | RSS: Main: 283631616 03:48:30 INFO - PROCESS | 1687 | 03:48:32 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:48:32 INFO - PROCESS | 1687 | RSS: Main: 285011968 03:48:32 INFO - PROCESS | 1687 | 03:48:35 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 03:48:36 INFO - PROCESS | 1687 | RSS: Main: 325144576 03:48:36 INFO - PROCESS | 1687 | 03:48:38 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 03:48:39 INFO - PROCESS | 1687 | RSS: Main: 325349376 03:48:39 INFO - PROCESS | 1687 | 03:48:42 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 03:48:42 INFO - PROCESS | 1687 | RSS: Main: 323530752 03:48:42 INFO - PROCESS | 1687 | 03:48:44 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:48:45 INFO - PROCESS | 1687 | RSS: Main: 299245568 03:48:45 INFO - PROCESS | 1687 | 03:48:47 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:48:47 INFO - PROCESS | 1687 | RSS: Main: 301621248 03:48:47 INFO - PROCESS | 1687 | 03:48:49 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:48:50 INFO - PROCESS | 1687 | RSS: Main: 303329280 03:48:50 INFO - PROCESS | 1687 | 03:48:53 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:48:54 INFO - PROCESS | 1687 | RSS: Main: 317263872 03:48:54 INFO - PROCESS | 1687 | 03:48:57 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:48:57 INFO - PROCESS | 1687 | RSS: Main: 316928000 03:48:57 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 03:48:59 INFO - PROCESS | 1687 | RSS: Main: 360767488 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | __start_tp_report 03:48:59 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 03:48:59 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 03:48:59 INFO - PROCESS | 1687 | |i|pagename|runs| 03:48:59 INFO - PROCESS | 1687 | |0;ai-astar;116;114;115;114;114;113;113;115;112;147 03:48:59 INFO - PROCESS | 1687 | |1;audio-beat-detection;147;135;139;178;141;114;112;106;167;131 03:48:59 INFO - PROCESS | 1687 | |2;audio-dft;184;183;180;184;195;259;184;180;185;183 03:48:59 INFO - PROCESS | 1687 | |3;audio-fft;81;59;59;89;61;60;58;58;82;58 03:48:59 INFO - PROCESS | 1687 | |4;audio-oscillator;85;70;95;69;68;70;92;68;69;93 03:48:59 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;90;84;87;84;87;85;87;84;87;84 03:48:59 INFO - PROCESS | 1687 | |6;imaging-darkroom;93;93;102;92;92;93;94;92;93;92 03:48:59 INFO - PROCESS | 1687 | |7;imaging-desaturate;83;81;83;81;81;84;84;84;82;81 03:48:59 INFO - PROCESS | 1687 | |8;json-parse-financial;56;56;56;54;56;58;59;58;57;57 03:48:59 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;66;72;69;76;73;73;71;70;70;71 03:48:59 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;85;84;85;85;84;83;82;84;84;84 03:48:59 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;145;165;152;132;145;160;138;153;196;194 03:48:59 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;162;167;161;162;146;203;218;163;165;166 03:48:59 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;67;67;66;68;68;67;68;65;67;70 03:48:59 INFO - PROCESS | 1687 | __end_tp_report 03:48:59 INFO - PROCESS | 1687 | __start_cc_report 03:48:59 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,2150 03:48:59 INFO - PROCESS | 1687 | __end_cc_report 03:48:59 INFO - PROCESS | 1687 | __startTimestamp1483357739821__endTimestamp 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | ------- Summary: start ------- 03:48:59 INFO - PROCESS | 1687 | Number of tests: 14 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:117.30 Median:114.50 stddev:10.50 (9.2%) stddev-sans-first:11.13 03:48:59 INFO - PROCESS | 1687 | Values: 116.0 114.0 115.0 114.0 114.0 113.0 113.0 115.0 112.0 147.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:140.00 stddev:23.23 (16.6%) stddev-sans-first:24.35 03:48:59 INFO - PROCESS | 1687 | Values: 147.0 135.0 139.0 178.0 141.0 114.0 112.0 106.0 167.0 131.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:191.70 Median:184.00 stddev:24.01 (13.0%) stddev-sans-first:25.30 03:48:59 INFO - PROCESS | 1687 | Values: 184.0 183.0 180.0 184.0 195.0 259.0 184.0 180.0 185.0 183.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:66.50 Median:60.50 stddev:12.29 (20.3%) stddev-sans-first:11.86 03:48:59 INFO - PROCESS | 1687 | Values: 81.0 59.0 59.0 89.0 61.0 60.0 58.0 58.0 82.0 58.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:77.50 stddev:11.78 (15.2%) stddev-sans-first:12.21 03:48:59 INFO - PROCESS | 1687 | Values: 85.0 70.0 95.0 69.0 68.0 70.0 92.0 68.0 69.0 93.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:2.02 (2.3%) stddev-sans-first:1.51 03:48:59 INFO - PROCESS | 1687 | Values: 90.0 84.0 87.0 84.0 87.0 85.0 87.0 84.0 87.0 84.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.00 stddev:3.03 (3.3%) stddev-sans-first:3.20 03:48:59 INFO - PROCESS | 1687 | Values: 93.0 93.0 102.0 92.0 92.0 93.0 94.0 92.0 93.0 92.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:83.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 03:48:59 INFO - PROCESS | 1687 | Values: 83.0 81.0 83.0 81.0 81.0 84.0 84.0 84.0 82.0 81.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.42 (2.5%) stddev-sans-first:1.48 03:48:59 INFO - PROCESS | 1687 | Values: 56.0 56.0 56.0 54.0 56.0 58.0 59.0 58.0 57.0 57.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:2.69 (3.8%) stddev-sans-first:2.12 03:48:59 INFO - PROCESS | 1687 | Values: 66.0 72.0 69.0 76.0 73.0 73.0 71.0 70.0 70.0 71.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:0.93 03:48:59 INFO - PROCESS | 1687 | Values: 85.0 84.0 85.0 85.0 84.0 83.0 82.0 84.0 84.0 84.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:156.50 stddev:21.78 (13.9%) stddev-sans-first:22.58 03:48:59 INFO - PROCESS | 1687 | Values: 145.0 165.0 152.0 132.0 145.0 160.0 138.0 153.0 196.0 194.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:165.50 stddev:21.76 (13.1%) stddev-sans-first:22.81 03:48:59 INFO - PROCESS | 1687 | Values: 162.0 167.0 161.0 162.0 146.0 203.0 218.0 163.0 165.0 166.0 03:48:59 INFO - PROCESS | 1687 | 03:48:59 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.34 (2.0%) stddev-sans-first:1.41 03:48:59 INFO - PROCESS | 1687 | Values: 67.0 67.0 66.0 68.0 68.0 67.0 68.0 65.0 67.0 70.0 03:48:59 INFO - PROCESS | 1687 | -------- Summary: end -------- 03:48:59 INFO - PROCESS | 1687 | 03:49:01 INFO - TEST-INFO | 1687: exit 0 03:49:01 INFO - TEST-OK | kraken | took 60371ms 03:49:01 INFO - SUITE-END | took 1180s 03:49:01 INFO - Completed test suite (00:19:41) 03:49:01 INFO - javascript benchmark 03:49:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.7825241359324, 3355.0, 3355.6308806031348, 3365.7812242204254, 3401.676840700134, 5667.603267771257, 5742.224799652056, 5764.654120752758, 5781.942180578193, 5793.884122317551, 5675.8581035474135, 5693.28833895763, 5771.942280577193, 5778.653280803144, 5797.217375654296, 5187.714675692835, 5237.47625237477, 5262.552683021929, 5285.550728188108, 5313.8937221255555, 5179.326687530615, 5180.326557547513, 5235.319408476893, 5341.172118321666, 5352.545033672123, 5228.790848366079, 5287.0, 5293.0, 5302.628815982883, 5315.14957606783, 5293.708846013467, 5354.946450535484, 5361.329833770778, 5366.812161574326, 5380.677159370451, 5044.445111037783, 5167.560757335594, 5245.344331958505, 5245.659032162897, 5291.708956007418, 5610.382857885629, 5638.379778224392, 5643.633163844337, 5659.943400565983, 5672.574556908207, 6257.092721555349, 6296.9370306296805, 6317.557770956035, 6321.936780632181, 6327.746890124389, 6164.445199931983, 6337.936620633781, 6353.110564520971, 6362.809115726489, 6384.425401713822, 5692.0, 5773.39379365169, 5809.7966571170655, 5870.4716575507555, 5878.147668588031, 6358.268799088071, 6396.904046439307, 6406.519511036691, 6410.45511131559, 6411.0, 5986.5809393342915, 6186.783462578788, 6194.163787888668, 6207.6585787782105, 6213.937860621426, 6177.505799536116, 6182.474489668429, 6212.502999760009, 6272.843178920563, 6285.560010799291, 6248.187735594434, 6254.405831446005, 6258.843528911813, 6262.499000079983, 6266.874662506815, 6245.968770156211, 6286.402791734778, 6291.559590828598, 6295.433410993032, 6319.33646967071, 4863.829765958175, 4913.606911447076, 4913.87715307113, 4917.704937703749, 4942.135126352874, 4403.295472724349, 4414.713043652152, 4428.047969686469, 4445.0, 4475.686701930834, 5751.884962300731, 5759.049756790167, 5768.769249229984, 5784.537237021028, 5803.88392232153, 6123.5876691309595, 6141.447269745744, 6151.507879369639, 6316.242050953915, 6325.3674632537295, 5692.857678558069, 5752.539796816244, 5766.798162064308, 5824.213731146241, 5827.125931110368, 5374.865628359323, 5423.647462914898, 5425.972870135703, 5429.4027656957705, 5437.537809286255, 6386.010168423994, 6387.137736405526, 6397.712102955378, 6412.0, 6416.48668106541, 4727.669063165683, 6112.533877023439, 6179.134921111138, 6293.37066293367, 6309.558330916791, 6372.235331760033, 6386.9680651596445, 6388.009858471944, 6415.518836087405, 6418.4865210783955, 6210.720517576629, 6217.906731398942, 6274.435300822948, 6286.811395658137, 6301.117843501822, 6109.61722939883, 6130.63216207029, 6187.010078387437, 6193.4425901669065, 6196.969015154896, 6055.424734650202, 6109.11417844419, 6114.785982490592, 6114.816555503342, 6126.0, 6121.4490695837585, 6128.050152226412, 6140.263168419819, 6144.2319710036245, 6158.630482171084, 5743.56923230751, 5848.239728835224, 5896.14505896634, 5901.114832775118, 5928.851778705567, 6168.629882207082, 6170.629762214281, 6174.38256174379, 6188.690565471798, 6189.96905015472, 5960.910586341121, 5991.011483105239, 6005.099235114768, 6007.0, 6052.152698622284], "value": 5732.395365401738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.789885602051, 478.98084076636934, 504.13540777566465, 515.58495411194, 553.9639923404981, 29911.252218694517, 31482.0, 38119.80940095299, 39106.608933910655, 39114.41328380069, 41348.79325603375, 41758.37362439558, 41779.95550111249, 41847.37228941561, 41881.79059104708, 9384.624615015407, 9484.573194206243, 9550.140487356137, 9566.521673916312, 9582.760430989247, 8536.359773017017, 8740.34447416443, 9465.526723663823, 11280.717982050473, 11541.480633371519, 11321.377324247163, 11362.545498180063, 11372.260803047817, 11401.087912966985, 11446.370449625267, 11422.42887855608, 11490.138239631977, 11521.193516453852, 11524.769504609862, 11525.366104864188, 8500.0, 8722.738317850411, 11034.282771619819, 11354.034907032912, 11376.772464550662, 39392.60607393947, 39649.20701585952, 39695.40456893149, 39724.60275397267, 39758.80120599378, 3204.9198770030703, 3221.548983142362, 3270.4767237242163, 3481.860725570974, 3507.3862074136923, 29065.41869162626, 30161.396772064654, 30259.546106808415, 30788.538171927215, 30897.073087807403, 2678.7187345328653, 3141.748660107186, 3187.2828613561765, 3241.8865339712997, 3269.264415506516, 475214.0, 480621.0, 481253.0, 481744.0, 486493.56753216364, 59825.80348393095, 59912.40087599155, 61367.693161533905, 61608.0, 61689.0, 61986.0, 62118.0, 63763.36236637667, 63841.0, 64107.03839442412, 15460.0, 15751.0, 16126.75809862853, 16127.193640317937, 16157.272922718506, 11166.0, 12215.69460763488, 12283.754324913452, 12292.692682683004, 12304.0, 15340.769888451681, 15591.922040389725, 17731.556711082067, 17944.641107178046, 18128.546786330182, 6696.430803381767, 6699.263081061079, 6771.221309549464, 6800.4219641330055, 6824.93175068243, 822.596927505518, 879.7668617816283, 942.4863449420083, 1055.1980494823836, 1099.433791597328, 28348.433031339257, 29215.707842921725, 29382.55926161109, 29909.252268693457, 30008.799648013835, 1028.1671845804922, 1047.7904419116207, 1052.3002203534718, 1064.622059168984, 1126.9042131418757, 14914.776278355834, 15149.545513634608, 15312.770308445382, 15338.616534586501, 15561.14413707257, 629760.0, 629941.7005829975, 630018.0, 635314.0, 635476.0, 56998.71500642553, 57009.714951424976, 57029.71485142548, 57066.7146664264, 57136.0, 1333.8466076401144, 1371.5748118083427, 1384.1487485196733, 1385.4873696732272, 1388.7500249954903, 28691.0, 28853.990110346036, 29146.417071658023, 29913.0, 30101.849490752407, 50341.74829125831, 50861.72845678888, 50994.49005510046, 51078.23382649189, 51086.0, 469226.3077369183, 471204.28795712977, 473343.0, 473597.0, 474249.0, 23326.883365583064, 26778.0, 27189.184324470298, 27330.453390932467, 40544.391834123104, 37213.25573488461, 38155.855324340315, 41473.585264146976, 45331.773341133085, 45548.86127846831, 36128.63871361253, 36662.63337366699, 37908.1045947707, 38145.0, 39529.604703953744, 37349.69276075324, 37909.62090379061, 38066.429003564415, 38234.8088259568, 39361.21277574375, 31116.0, 37208.44187337138, 38017.2396552073, 38144.0, 38163.236735265695, 32655.510167347034, 33918.491222632176, 34424.82787586046, 34773.652263478056, 34810.0, 25252.0, 25278.115265965604, 25287.0, 25596.360090997874, 25770.0], "value": 19726.144685078583, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.45639922327763, 371.867986864663, 386.30272358393097, 393.8129388540442, 406.39041437843235, 18811.435656930298, 19186.0, 20104.597908041847, 20242.696359554582, 20539.486512837186, 11702.882971170307, 11777.175597708163, 12197.207181533195, 12248.571300004502, 12254.264744115337, 5194.324737784093, 5502.477264659871, 5513.531349835249, 5522.91715624266, 5525.0883604205255, 3824.3116239076953, 4033.9394909076386, 4041.636252737253, 5075.441701412841, 5425.945740542584, 4122.278601244785, 6648.434883034948, 6686.4985126115735, 6705.161854768154, 6716.899246511306, 6046.727897244635, 6103.328633850273, 6271.34150914152, 6693.096431981693, 6701.564398314094, 5913.46778789911, 5914.822555323347, 5920.585559010871, 5928.644281343133, 5947.375525569796, 12908.225506469642, 13139.737205255937, 13230.073894827367, 13239.735205295936, 13240.735185296337, 2134.96603237236, 2151.34384012876, 2247.3033359658557, 2254.921077762287, 2265.5468906218657, 10426.843597346045, 10680.839787403194, 11050.226484146113, 13546.59360219195, 15423.922880385526, 1799.4151900632216, 1846.7876194237701, 1847.316492897623, 1924.9422517324363, 1987.21505005522, 92676.53661731648, 121770.0, 122523.0, 126359.0, 126637.36681316627, 9552.952235238918, 11226.203807390779, 11706.473208705629, 11711.355875426763, 11742.70643233909, 14675.706485870223, 14731.705365892622, 14753.0, 14757.557273281818, 14761.704765904622, 8244.340452763765, 8468.44955077918, 8516.74449766508, 8665.913340866511, 8671.4363566368, 6766.729330826712, 8339.999200096026, 8345.874811877828, 8502.574871256413, 8520.0, 4095.52901416335, 4218.7679677618025, 4555.362249285103, 4559.794809233593, 4639.072185562901, 1641.958951026234, 1750.212404418017, 1894.895780732073, 1926.6628340040436, 1935.438722770409, 671.1007250284642, 707.8568112498278, 880.9119088091139, 883.911608839111, 938.8075444533854, 18437.631247375244, 18442.723359149622, 20007.299744508873, 21098.894505527373, 21642.0, 869.6086760957468, 897.7261935109724, 908.3596064774318, 922.4096578189964, 928.9442633442015, 8151.673933042612, 10863.402512861869, 11153.0, 11311.208215425006, 11428.828567571492, 596931.0, 631445.0, 639352.0, 641632.0, 656636.0, 70635.64682176556, 114956.24622058295, 124481.0, 134925.0, 142387.0, 866.8786369908217, 971.8056388722284, 987.5802783816993, 1003.1072345612411, 1008.541113793237, 13219.0, 17631.0, 18889.105476501252, 19725.785489647486, 19837.900810496434, 53429.732851335495, 54047.91904161973, 54888.90222195454, 55640.72179639076, 56342.71828640831, 69431.30568694249, 73765.26234737798, 74377.51244975178, 75923.0, 76274.0, 6774.254831968381, 7195.244499327495, 7407.481476296607, 7465.701371945279, 8282.50304981703, 12288.75422491563, 12548.372581371077, 12746.171498852917, 12947.223166610034, 12947.66941950458, 10311.535980880728, 10628.36229826213, 10754.83867742, 10821.9458902705, 11105.278156919774, 10713.732156696145, 10721.35671859691, 10793.29843560166, 11170.944145279222, 11191.16066295015, 9549.856752148862, 10490.213234007328, 10909.945450272699, 11117.722056948642, 11477.0, 6559.245686746085, 7959.442839001446, 7962.163972782774, 8422.705205317785, 8505.277051450696, 8210.507369557845, 10165.390076595428, 10805.783884322112, 11109.777804444027, 11148.498317575566], "value": 9063.689980612791, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.854114588541, 1475.159159279211, 1624.3502598960415, 1758.3054693396116, 1815.6731788278114, 7901.920980790191, 8415.368847336444, 8418.200270974263, 8429.241368276855, 8442.746717598475, 7392.0, 7579.507332023414, 7593.278638529331, 7715.768526944186, 8214.876776848352, 2090.5714328562685, 2118.03629348646, 2126.13891373993, 2250.3698964290024, 2330.1145564685394, 2353.223436266028, 2363.4918492524102, 2364.834461587681, 2369.39580406996, 2370.5614461324626, 2290.885455727207, 2327.197116994634, 2347.190219374313, 2354.3996280948336, 2354.8704821234824, 2308.0652335804025, 2315.9305220843403, 2338.1582630253097, 2342.875419798489, 2345.5543446745232, 2318.4783423729696, 2328.650702394654, 2331.813454923602, 2362.090595120874, 2363.7399886012527, 8230.176982301782, 8246.546439945801, 8294.004719433646, 8324.833503329899, 8334.20825021622, 5061.139606266944, 5227.738613069331, 5228.947710522884, 5236.109861323546, 5244.134717771602, 3492.877749278763, 3500.6149323574386, 3510.4207805712026, 3527.0476971217886, 3592.4611308303965, 1057.8466122412285, 1058.7406085509099, 1080.5353697909895, 1091.7434402915308, 1101.8677758669012, 10771.999999999922, 10854.077403420719, 10855.077318427942, 10865.83701244482, 10924.726881827859, 4467.7319360838455, 4611.446626404853, 4727.0, 4732.02993386355, 4763.880902977384, 9182.403143795633, 9385.779848619635, 9388.90611093894, 9389.577469013911, 9405.717828465156, 5102.770375333119, 5107.387113546398, 5114.0, 5173.586113110942, 5224.9738751306, 4566.292224705176, 4715.104130215248, 4771.5228477152, 4792.257200133985, 4793.616510679138, 3590.79708297718, 3679.2273622539033, 3705.6850167735506, 3759.285735710206, 3790.3556395412847, 1249.6938250128776, 1260.2249616485979, 1260.5462033667873, 1274.2927675140386, 1275.489804078376, 850.3154960257052, 868.6829307302789, 876.2945828607905, 890.9109089091039, 900.3247564326755, 5538.418466061086, 5631.802886898939, 5657.745401456944, 5699.914501282484, 5774.335951365647, 794.8251384695358, 806.842181469593, 912.2428384440898, 941.6751220828803, 981.8919918808925, 3738.9065273368383, 3744.2885851688093, 3752.5121734174927, 3776.4712940188397, 3828.9617103828605, 9548.42709437436, 9616.182624476858, 10041.497925103715, 10123.341982771244, 10132.189424846141, 9899.30704850668, 10075.546600402999, 10081.445520496443, 10099.192064634812, 10104.09063184317, 7528.623568821537, 7822.960885195537, 7845.0978137513775, 7848.215178482106, 7861.921380786233, 5534.806281780118, 5587.804426845041, 5626.774929002794, 5749.8275051748515, 5801.0, 9276.907230927603, 9344.486053267136, 9418.858717119385, 9448.385854919408, 9459.527023648927, 8971.641134354553, 9287.349885507949, 9301.906980930375, 9339.252859771066, 9513.191378732608, 7025.929740702528, 7190.42476601881, 7202.89195662055, 7207.531510451804, 7213.422926165789, 6984.2317345093015, 7199.0, 7208.026916366223, 7716.922830771845, 7807.843843123219, 6841.110655614738, 6851.897221541781, 7078.964605176941, 7112.928870711226, 7134.678939447841, 6723.327667233238, 6929.376356127973, 7015.894761578478, 7056.1179852518435, 7088.184858741104, 6471.935280647322, 6693.832654183684, 6705.430038446787, 6708.798736037927, 6718.529702920749, 4556.587457888066, 4559.521250268674, 4648.558386953234, 4699.083678682624, 4794.520547945317, 4731.195696731495, 4790.808367665254, 4798.95201047985, 4838.201696720002, 4986.825461108989], "value": 4401.94202512992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.47079962267344, 300.17002986741664, 310.9238236632024, 314.8048210109731, 316.51257064121256, 4239.872803815891, 4522.253828118365, 4564.634829213664, 5204.791808327667, 5336.893262134755, 4249.851255206061, 5049.495050494948, 5091.199840087956, 5242.868928276795, 5305.585958388924, 4026.21488809683, 4038.9394159087637, 4054.209429161315, 4066.654334381581, 4073.2464494068545, 3861.4014827701753, 3886.7950935210033, 4035.4350390945147, 4050.9392359114636, 4073.40935564343, 3156.905292841207, 3892.9805350973206, 4044.3933409988445, 4053.148838743867, 4053.885181575061, 3970.5110583531177, 3985.262726395626, 4007.5391329997133, 4024.8188831502653, 4037.9905023744063, 3476.461148521983, 3479.5476588043643, 3479.7564170508076, 3485.8779942701885, 3494.6155922848466, 4242.109157077018, 4993.825216117419, 5106.336176297094, 5162.5611822994715, 5196.428392876781, 3614.8011859347725, 4247.978760106211, 4520.932186017212, 4610.953890461086, 4630.907381852344, 4983.04526091321, 5224.4775522447835, 5225.159507761792, 5561.805336813232, 5562.221289019541, 3070.0175943698027, 3188.2507610711464, 3283.589551306087, 3816.996077059538, 3843.0200298923874, 5352.68859129508, 5510.476504732044, 5760.745178973866, 5836.533077353844, 5862.101702367921, 3082.7533797296164, 3109.5589961879814, 3298.67013298673, 3920.627540383659, 3929.901752456154, 3996.820143093568, 3997.5003124609425, 4003.53959294679, 4005.7181701855125, 4005.759654420744, 4104.835806567704, 4472.530384309665, 4493.28107502798, 4549.363089167519, 4556.883563526957, 4903.0, 5473.9452605474235, 5491.423400542965, 5493.258410114663, 5616.241807356037, 1175.9588414405455, 1176.405915012913, 1202.5430336472084, 1226.6994586326407, 1263.6082814327588, 802.6628815897337, 822.6668199379261, 836.8200836820176, 859.4155973937783, 866.9783255418539, 4691.0, 4838.467768545457, 4920.720612640667, 4935.703857768545, 4940.827071052496, 4880.755962201875, 5347.3048503695045, 5383.515483606494, 5385.734352427176, 5392.409239274423, 4148.709590328708, 4561.589456948891, 4720.527947205321, 4854.538818812207, 4909.435414927258, 2726.4410795786816, 2739.986300068527, 2741.7121202273875, 2774.1122840690787, 2788.316862368692, 4845.563899249014, 4880.267959805987, 5296.622878051658, 5454.636340914772, 5500.697461639568, 5100.744962751847, 5367.919481207785, 5371.597130215249, 5395.406505284415, 5446.618736688394, 5476.58925580583, 5532.5297349724915, 5575.804846830341, 5595.468430499096, 5618.269624948731, 4766.380370551806, 5010.248462730619, 5020.439575816287, 5383.757730902197, 5417.6478528895495, 4746.92879606813, 5018.623603229699, 5232.744141406111, 5360.758765855624, 5690.94717477268, 4984.0, 5302.019126461617, 5893.0, 5933.199018132497, 5987.461128498282, 3827.9617203827606, 3853.5761066282125, 3872.1094148345474, 3919.2749341371937, 3944.3886197638262, 5426.728663566727, 5706.0, 5778.306603207642, 5802.0, 5825.912611310749, 4908.833103668684, 5275.419703832652, 5434.266374039454, 5447.439703339424, 5636.323641163087, 5124.0, 5574.08027675429, 5608.327000759935, 5637.210790489253, 5672.355017045011, 4357.213542447581, 5081.466446023114, 5391.272178256042, 5457.590680698884, 5555.166864899012, 4406.405135306821, 4603.516630753723, 5432.854022661106, 5575.330960284792, 5593.776248950159, 4679.461861885927, 5040.49595040493, 5424.593155513431, 5557.5553955682635, 5566.276384070045], "value": 3959.0433118936508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.9702113404898, 711.9430445564359, 732.2969948849104, 750.0699133074988, 773.4663082473095, 13666.931665341672, 14418.711625767504, 14469.565913022612, 14483.710325793478, 14493.0, 12802.0, 12885.033622478304, 12896.290704011299, 12897.226166429997, 12912.741745165114, 627.2097157581435, 648.6237981970446, 769.8267889724823, 788.9368850491946, 800.1678254615193, 783.6316931042401, 790.8220650353653, 794.6265255329986, 795.1174196641723, 796.0566728426833, 835.0688981785296, 837.5602808525542, 838.4675730910885, 839.3998291221775, 840.3487297344576, 651.5992664511339, 679.2765704524666, 828.5443006346545, 828.6726742936537, 830.5598033042437, 502.63307785316925, 503.73553884210713, 504.095149207174, 600.6936462404161, 624.622103627308, 13676.863231367659, 13749.518766843212, 13769.586912392644, 13782.517611883637, 13791.0, 12505.687357816036, 12717.809232861515, 12751.362431878462, 12802.423890924838, 12809.10336276461, 10473.895261047368, 10748.086412654933, 10931.83602245959, 12054.698632534257, 12100.258428872203, 4119.567445418248, 4146.390966649686, 4219.91560168798, 4253.255680255974, 4295.463067116611, 79946.20053799446, 79989.60005199937, 80518.19481805165, 81053.0, 82261.58869205676, 8427.620757065946, 9791.873934497482, 9937.354071985297, 9998.500074996222, 10160.69517914449, 18720.438386848415, 18724.344647937258, 18729.906350468435, 18744.437666870017, 18769.624607508045, 6861.279565645636, 7034.120734908137, 7283.417326613858, 7384.630768461556, 7427.962860185665, 4133.81397837098, 4222.59885310895, 4234.682398820039, 4243.490781106287, 4263.55232700562, 3172.476541370648, 3298.1589694628196, 3310.9901480048816, 3337.4159522084024, 3340.682635149657, 666.1040899989506, 676.6496430995048, 680.7072958627739, 690.8031211104811, 694.9027136200936, 548.6762809942153, 553.6124712701045, 553.7120697237414, 605.7788907048896, 659.304433822322, 8484.703035393855, 8979.640814367483, 9014.63941442348, 9261.351705380694, 9522.761930951643, 529.1612793721916, 584.3017593975159, 609.7439075588263, 652.1587152573222, 658.256170527301, 3833.9808300958316, 4123.2165888481095, 4374.650027997753, 4383.9342409863875, 4407.096545208225, 85526.0, 88782.55608721916, 91807.5409622961, 99973.0, 115872.0, 57111.428885711444, 61957.0, 76872.23127768762, 80724.59637701773, 85697.57151214204, 76356.61821690967, 82125.0, 82192.0, 82301.58849205716, 83887.58056209802, 9051.54742262871, 9401.34190606651, 9454.37840482963, 9486.288528360263, 9513.90486095158, 52350.476495236086, 53249.20126198188, 53253.73373133109, 53473.73263133815, 53746.92506149777, 61596.38403615906, 62235.37764622296, 66419.33951651247, 67734.32265677478, 69369.65315173392, 11969.16215864881, 11993.580224692094, 12093.213941093802, 12294.569690060805, 12398.25610463375, 12984.870151298745, 13905.582832515041, 13999.790003149757, 14634.414623414945, 14782.113073215642, 11944.069997065244, 12614.243145411305, 12638.684032898884, 13054.05029873575, 13064.0, 12725.872741272839, 12977.675558111123, 13424.194548326741, 13454.93272533631, 13737.862621373659, 12099.879001209874, 12338.0, 12757.0, 12771.169873958162, 12877.613671589866, 9324.0, 10193.216187167365, 10595.735106622396, 11038.392888391216, 11164.162687798285, 10526.421046842497, 10705.678829635124, 10937.507812148631, 10950.72623184395, 10979.945100274766], "value": 6973.722465277476, "unit": "score"}], "name": "dromaeo_css", "value": 7067.053341361665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 114.0, 114.0, 113.0, 113.0, 115.0, 112.0, 147.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 135.0, 139.0, 178.0, 141.0, 114.0, 112.0, 106.0, 167.0, 131.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 180.0, 184.0, 195.0, 259.0, 184.0, 180.0, 185.0, 183.0], "value": 191.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 61.0, 60.0, 58.0, 58.0, 82.0, 58.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 95.0, 69.0, 68.0, 70.0, 92.0, 68.0, 69.0, 93.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 102.0, 92.0, 92.0, 93.0, 94.0, 92.0, 93.0, 92.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 81.0, 81.0, 84.0, 84.0, 84.0, 82.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 54.0, 56.0, 58.0, 59.0, 58.0, 57.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 69.0, 76.0, 73.0, 73.0, 71.0, 70.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 84.0, 83.0, 82.0, 84.0, 84.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 165.0, 152.0, 132.0, 145.0, 160.0, 138.0, 153.0, 196.0, 194.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 161.0, 162.0, 146.0, 203.0, 218.0, 163.0, 165.0, 166.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 68.0, 67.0, 68.0, 65.0, 67.0, 70.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1460.6999999999998, "alertThreshold": 2.0}]} 03:49:01 INFO - Return code: 0 03:49:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:01 INFO - # TBPL SUCCESS # 03:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:49:01 INFO - 2017-01-02 03:49:01.644 screenresolution[1691:12321] starting screenresolution argv=screenresolution get 03:49:01 INFO - 2017-01-02 03:49:01.647 screenresolution[1691:12321] Display 0: 1600x1200x32@60 03:49:01 INFO - 2017-01-02 03:49:01.653 screenresolution[1692:12324] starting screenresolution argv=screenresolution list 03:49:01 INFO - Available Modes on Display 0 03:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:49:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:49:01 INFO - Intel Iris: 03:49:01 INFO - Chipset Model: Intel Iris 03:49:01 INFO - Type: GPU 03:49:01 INFO - Bus: Built-In 03:49:01 INFO - VRAM (Dynamic, Max): 1536 MB 03:49:01 INFO - Vendor: Intel (0x8086) 03:49:01 INFO - Device ID: 0x0a2e 03:49:01 INFO - Revision ID: 0x0009 03:49:01 INFO - Displays: 03:49:01 INFO - Display: 03:49:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:49:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:49:01 INFO - Display Serial Number: 1600x1200 60 03:49:01 INFO - Main Display: Yes 03:49:01 INFO - Mirror: Off 03:49:01 INFO - Online: Yes 03:49:01 INFO - Rotation: Supported 03:49:01 INFO - Return code: 0 03:49:01 INFO - Running post-action listener: _resource_record_post_action 03:49:01 INFO - [mozharness: 2017-01-02 11:49:01.887123Z] Finished run-tests step (success) 03:49:01 INFO - Running post-run listener: _resource_record_post_run 03:49:01 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32439296; Write bytes: 660715008; Read time: 194; Write time: 1896 03:49:01 INFO - TinderboxPrint: CPU usage
53.3% 03:49:01 INFO - TinderboxPrint: I/O read bytes / time
32,439,296 / 194 03:49:01 INFO - TinderboxPrint: I/O write bytes / time
660,715,008 / 1,896 03:49:01 INFO - TinderboxPrint: CPU idle
2,264.3 (46.7%) 03:49:01 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 03:49:01 INFO - TinderboxPrint: CPU user
1,617.3 (33.3%) 03:49:01 INFO - TinderboxPrint: Swap in / out
377,192,448 / 0 03:49:01 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346171904; Write bytes: 346994176; Read time: 26568; Write time: 727 03:49:01 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 30362624; Write bytes: 306573312; Read time: 175; Write time: 1141 03:49:02 INFO - Running post-run listener: _upload_blobber_files 03:49:02 INFO - Blob upload gear active. 03:49:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:02 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 03:49:02 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:02 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:02 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:03 INFO - (blobuploader) - INFO - Done attempting. 03:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:49:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:04 INFO - (blobuploader) - INFO - Done attempting. 03:49:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:07 INFO - (blobuploader) - INFO - Done attempting. 03:49:07 INFO - (blobuploader) - INFO - Iteration through files over. 03:49:07 INFO - Return code: 0 03:49:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:49:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:49:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a163a0fde5d45579ac9e863316659baceafaf19ef39541235d3bbf07bcb9eb5bdf21e1c447012dcc85ada6f0e95a76c8e6f6be6a4512b6f77f73d98e30f067f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aafbe345140df27a36ebfbd798daeb914808c54207112c4cad52cbf1af2f726c42f13f5f079e6ec63712ef666523e2b6928617be04aeab75e224d407a467d1d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f1bfcaca45737bd8757f2089f3f091b93a3896459a7c05b658c94ebf3923e3291a9bb54c51991fe46218cfcec092e735ee5f8d3c3252caae2ca403e6973e67b"} 03:49:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:49:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:49:07 INFO - Contents: 03:49:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a163a0fde5d45579ac9e863316659baceafaf19ef39541235d3bbf07bcb9eb5bdf21e1c447012dcc85ada6f0e95a76c8e6f6be6a4512b6f77f73d98e30f067f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aafbe345140df27a36ebfbd798daeb914808c54207112c4cad52cbf1af2f726c42f13f5f079e6ec63712ef666523e2b6928617be04aeab75e224d407a467d1d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f1bfcaca45737bd8757f2089f3f091b93a3896459a7c05b658c94ebf3923e3291a9bb54c51991fe46218cfcec092e735ee5f8d3c3252caae2ca403e6973e67b"} 03:49:07 INFO - Running post-run listener: copy_logs_to_upload_dir 03:49:07 INFO - Copying logs to upload dir... 03:49:07 INFO - mkdir: /builds/slave/test/build/upload/logs 03:49:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.576878 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-02 03:49:07.211990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 03:49:07.215278) ========= 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.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a163a0fde5d45579ac9e863316659baceafaf19ef39541235d3bbf07bcb9eb5bdf21e1c447012dcc85ada6f0e95a76c8e6f6be6a4512b6f77f73d98e30f067f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aafbe345140df27a36ebfbd798daeb914808c54207112c4cad52cbf1af2f726c42f13f5f079e6ec63712ef666523e2b6928617be04aeab75e224d407a467d1d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f1bfcaca45737bd8757f2089f3f091b93a3896459a7c05b658c94ebf3923e3291a9bb54c51991fe46218cfcec092e735ee5f8d3c3252caae2ca403e6973e67b"} build_url:https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010887 build_url: 'https://queue.taskcluster.net/v1/task/Q2-LPRifTti47IpuslK39g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a163a0fde5d45579ac9e863316659baceafaf19ef39541235d3bbf07bcb9eb5bdf21e1c447012dcc85ada6f0e95a76c8e6f6be6a4512b6f77f73d98e30f067f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aafbe345140df27a36ebfbd798daeb914808c54207112c4cad52cbf1af2f726c42f13f5f079e6ec63712ef666523e2b6928617be04aeab75e224d407a467d1d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f1bfcaca45737bd8757f2089f3f091b93a3896459a7c05b658c94ebf3923e3291a9bb54c51991fe46218cfcec092e735ee5f8d3c3252caae2ca403e6973e67b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 03:49:07.248326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:49:07.248624) ========= 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.JukKdidfBj/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.6k7TbkFdaY/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005330 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 03:49:07.296340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 03:49:07.296627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 03:49:07.296924) ========= ========= Total master_lag: 0.23 =========