builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0129 starttime: 1484102299.13 results: success (0) buildid: 20170110173105 builduid: 5e1a4026a1784679999a76c129b8ee47 revision: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.127963) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.128391) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.128672) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.154335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.154710) ========= 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.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-10 18:38:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.19M=0.001s 2017-01-10 18:38:19 (8.19 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.450796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.629968) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.630244) ========= 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.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.019981 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:19.678207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 18:38:19.678531) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 --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 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-10 18:38:19,756 truncating revision to first 12 chars 2017-01-10 18:38:19,756 Setting DEBUG logging. 2017-01-10 18:38:19,756 attempt 1/10 2017-01-10 18:38:19,756 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5cf9f0f65d20?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 18:38:20,862 unpacking tar archive at: mozilla-aurora-5cf9f0f65d20/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 18:38:21.061751) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:21.062055) ========= script_repo_revision: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:21.062487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:21.062758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 18:38:21.083224) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-10 18:38:21.083633) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:38:21 INFO - MultiFileLogger online at 20170110 18:38:21 in /builds/slave/test 18:38:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:38:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:38:21 INFO - {'append_to_log': False, 18:38:21 INFO - 'base_work_dir': '/builds/slave/test', 18:38:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:38:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:38:21 INFO - 'branch': 'Mozilla-Aurora', 18:38:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:38:21 INFO - 'config_files': ('talos/mac_config.py',), 18:38:21 INFO - 'default_actions': ('clobber', 18:38:21 INFO - 'read-buildbot-config', 18:38:21 INFO - 'download-and-extract', 18:38:21 INFO - 'populate-webroot', 18:38:21 INFO - 'create-virtualenv', 18:38:21 INFO - 'install', 18:38:21 INFO - 'run-tests'), 18:38:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:38:21 INFO - 'download_minidump_stackwalk': True, 18:38:21 INFO - 'download_symbols': 'ondemand', 18:38:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:38:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:38:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:38:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:38:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:38:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:38:21 INFO - 'installer_path': 'installer.exe', 18:38:21 INFO - 'log_level': 'info', 18:38:21 INFO - 'log_name': 'talos', 18:38:21 INFO - 'log_to_console': True, 18:38:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:38:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:38:21 INFO - 'opt_config_files': (), 18:38:21 INFO - 'pip_index': False, 18:38:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:38:21 INFO - 'cmd': ('bash', 18:38:21 INFO - '-c', 18:38:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:38:21 INFO - 'enabled': True, 18:38:21 INFO - 'halt_on_failure': False, 18:38:21 INFO - 'name': 'check_screen_resolution'},), 18:38:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:38:21 INFO - 'cmd': ('bash', 18:38:21 INFO - '-c', 18:38:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:38:21 INFO - 'enabled': True, 18:38:21 INFO - 'halt_on_failure': False, 18:38:21 INFO - 'name': 'check_screen_resolution'},), 18:38:21 INFO - 'run_cmd_checks_enabled': True, 18:38:21 INFO - 'sps_profile': False, 18:38:21 INFO - 'sps_profile_interval': 0, 18:38:21 INFO - 'suite': 'dromaeojs-e10s', 18:38:21 INFO - 'system_bits': '32', 18:38:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:38:21 INFO - 'title': 't-yosemite-r7-0129', 18:38:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:38:21 INFO - 'use_talos_json': True, 18:38:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:38:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:38:21 INFO - 'work_dir': 'build'} 18:38:21 INFO - [mozharness: 2017-01-11 02:38:21.287537Z] Running clobber step. 18:38:21 INFO - Running pre-action listener: _resource_record_pre_action 18:38:21 INFO - Running main action method: clobber 18:38:21 INFO - rmtree: /builds/slave/test/build 18:38:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:38:22 INFO - Running post-action listener: _resource_record_post_action 18:38:22 INFO - [mozharness: 2017-01-11 02:38:22.835917Z] Finished clobber step (success) 18:38:22 INFO - [mozharness: 2017-01-11 02:38:22.836096Z] Running read-buildbot-config step. 18:38:22 INFO - Running pre-action listener: _resource_record_pre_action 18:38:22 INFO - Running main action method: read_buildbot_config 18:38:22 INFO - Using buildbot properties: 18:38:22 INFO - { 18:38:22 INFO - "project": "", 18:38:22 INFO - "product": "firefox", 18:38:22 INFO - "script_repo_revision": "production", 18:38:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:38:22 INFO - "repository": "", 18:38:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:38:22 INFO - "buildid": "20170110173105", 18:38:22 INFO - "pgo_build": "False", 18:38:22 INFO - "basedir": "/builds/slave/test", 18:38:22 INFO - "buildnumber": 33, 18:38:22 INFO - "slavename": "t-yosemite-r7-0129", 18:38:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:38:22 INFO - "platform": "yosemite_r7", 18:38:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:38:22 INFO - "branch": "mozilla-aurora", 18:38:22 INFO - "repo_path": "releases/mozilla-aurora", 18:38:22 INFO - "revision": "5cf9f0f65d201fc4631530c8f00e3a14887c1ad1", 18:38:22 INFO - "stage_platform": "macosx64", 18:38:22 INFO - "builduid": "5e1a4026a1784679999a76c129b8ee47", 18:38:22 INFO - "slavebuilddir": "test" 18:38:22 INFO - } 18:38:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:38:22 INFO - Running post-action listener: _resource_record_post_action 18:38:22 INFO - [mozharness: 2017-01-11 02:38:22.839461Z] Finished read-buildbot-config step (success) 18:38:22 INFO - [mozharness: 2017-01-11 02:38:22.839601Z] Running download-and-extract step. 18:38:22 INFO - Running pre-action listener: _resource_record_pre_action 18:38:22 INFO - Running main action method: download_and_extract 18:38:22 INFO - mkdir: /builds/slave/test/build/tests 18:38:22 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')]} 18:38:22 INFO - trying https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:38:22 INFO - Downloading https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/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 18:38:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/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 18:38:24 INFO - Downloaded 1199 bytes. 18:38:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:38:24 INFO - Using the following test package requirements: 18:38:24 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:38:24 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:38:24 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:38:24 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'jsshell-mac.zip'], 18:38:24 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:38:24 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:38:24 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:38:24 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:38:24 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:38:24 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:38:24 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:38:24 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:38:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:38:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:38:24 INFO - Fetch https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:38:24 INFO - Http code: 200 18:38:24 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 18:38:24 INFO - x-amz-cf-id: Wj427_laljHYkFxektcKFZ3DanOkXsNREU9twlFK0He8VKQdQazlaQ== 18:38:24 INFO - x-amz-version-id: r31MbaFKmn5l5_wd9isL1y6utfB9MG.F 18:38:25 INFO - Expected file size: 16271691 18:38:25 INFO - Obtained file size: 16271691 18:38:31 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 18:38:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:38:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:38:31 INFO - Fetch https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:38:32 INFO - Http code: 200 18:38:32 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 18:38:32 INFO - x-amz-cf-id: EDXJWi8fvkGwi7V27ZiPX2klHNEFN8fSE3Fdytd-0-gpQ6V0DMLFug== 18:38:32 INFO - x-amz-version-id: r31MbaFKmn5l5_wd9isL1y6utfB9MG.F 18:38:32 INFO - Expected file size: 16271691 18:38:32 INFO - Obtained file size: 16271691 18:38:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:38:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:38:38 INFO - Fetch https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:38:39 INFO - Http code: 200 18:38:39 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 18:38:39 INFO - x-amz-cf-id: 6czc_DE7KleFWHnXdgCQpw5e-CV62GIQWPIFL-HHTREHXQ1yTULr5A== 18:38:39 INFO - x-amz-version-id: Q3or11QCmRhTB3Bmx45Ow5UlQNAC.1q5 18:38:40 INFO - Expected file size: 14409038 18:38:40 INFO - Obtained file size: 14409038 18:38:40 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')]} 18:38:40 INFO - trying https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:38:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:38:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/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 18:38:43 INFO - Downloaded 109865589 bytes. 18:38:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:38:43 INFO - mkdir: /builds/slave/test/properties 18:38:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:38:43 INFO - Writing to file /builds/slave/test/properties/build_url 18:38:43 INFO - Contents: 18:38:43 INFO - build_url:https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:38:43 INFO - Running post-action listener: _resource_record_post_action 18:38:43 INFO - Running post-action listener: set_extra_try_arguments 18:38:43 INFO - [mozharness: 2017-01-11 02:38:43.846433Z] Finished download-and-extract step (success) 18:38:43 INFO - [mozharness: 2017-01-11 02:38:43.846578Z] Running populate-webroot step. 18:38:43 INFO - Running pre-action listener: _resource_record_pre_action 18:38:43 INFO - Running main action method: populate_webroot 18:38:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:38:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:38:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:38:43 INFO - '--tppagecycles', 18:38:43 INFO - '7'], 18:38:43 INFO - 'tests': ['tsvgm']}}, 18:38:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:38:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:38:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:38:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:38:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['damp', 'tps']}, 18:38:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'tests': ['damp', 'tps']}, 18:38:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['dromaeo_dom']}, 18:38:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:38:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['basic_compositor_video']}, 18:38:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:38:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['a11yr', 18:38:43 INFO - 'ts_paint', 18:38:43 INFO - 'tpaint', 18:38:43 INFO - 'sessionrestore', 18:38:43 INFO - 'sessionrestore_no_auto_restore', 18:38:43 INFO - 'tabpaint']}, 18:38:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['tsvgx', 18:38:43 INFO - 'tsvgr_opacity', 18:38:43 INFO - 'tart', 18:38:43 INFO - 'tscrollx', 18:38:43 INFO - 'cart']}, 18:38:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:38:43 INFO - 'tsvgr_opacity', 18:38:43 INFO - 'tart', 18:38:43 INFO - 'tscrollx', 18:38:43 INFO - 'cart']}, 18:38:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'talos_options': ['--disable-e10s'], 18:38:43 INFO - 'tests': ['tp5o']}, 18:38:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'tests': ['tp5o']}, 18:38:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'talos_options': ['--disable-e10s', 18:38:43 INFO - '--xperf_path', 18:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:43 INFO - 'tests': ['tp5n']}, 18:38:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:43 INFO - 'talos_options': ['--xperf_path', 18:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:43 INFO - 'tests': ['tp5n']}}, 18:38:43 INFO - 'talos.zip': {'path': '', 18:38:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:38:43 INFO - Running post-action listener: _resource_record_post_action 18:38:43 INFO - [mozharness: 2017-01-11 02:38:43.865049Z] Finished populate-webroot step (success) 18:38:43 INFO - [mozharness: 2017-01-11 02:38:43.865180Z] Running create-virtualenv step. 18:38:43 INFO - Running pre-action listener: _resource_record_pre_action 18:38:43 INFO - Running main action method: create_virtualenv 18:38:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:38:43 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 18:38:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:38:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:38:43 INFO - Using real prefix '/tools/python27' 18:38:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:38:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:38:47 INFO - Installing pip.................done. 18:38:47 INFO - Return code: 0 18:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:38:48 INFO - Reading from file tmpfile_stdout 18:38:48 INFO - Output received: 18:38:48 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) 18:38:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:38: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', 'pip>=1.5'] in /builds/slave/test/build 18:38: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 pip>=1.5 18:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:48 INFO - 'HOME': '/Users/cltbld', 18:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:48 INFO - 'LOGNAME': 'cltbld', 18:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:48 INFO - 'MOZ_NO_REMOTE': '1', 18:38:48 INFO - 'NO_EM_RESTART': '1', 18:38:48 INFO - 'PAGER': '/bin/cat', 18:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:48 INFO - 'PWD': '/builds/slave/test', 18:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:48 INFO - 'SHELL': '/bin/bash', 18:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:38:48 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:48 INFO - 'USER': 'cltbld', 18:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:48 INFO - 'XPC_FLAGS': '0x0', 18:38:48 INFO - 'XPC_SERVICE_NAME': '0', 18:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:48 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 18:38:48 INFO - Cleaning up... 18:38:48 INFO - Return code: 0 18:38:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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', '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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:38: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:38: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 psutil>=3.1.1 18:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:48 INFO - 'HOME': '/Users/cltbld', 18:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:48 INFO - 'LOGNAME': 'cltbld', 18:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:48 INFO - 'MOZ_NO_REMOTE': '1', 18:38:48 INFO - 'NO_EM_RESTART': '1', 18:38:48 INFO - 'PAGER': '/bin/cat', 18:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:48 INFO - 'PWD': '/builds/slave/test', 18:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:48 INFO - 'SHELL': '/bin/bash', 18:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:38:48 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:48 INFO - 'USER': 'cltbld', 18:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:48 INFO - 'XPC_FLAGS': '0x0', 18:38:48 INFO - 'XPC_SERVICE_NAME': '0', 18:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:48 INFO - Downloading/unpacking psutil>=3.1.1 18:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:38:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:52 INFO - Installing collected packages: psutil 18:38:52 INFO - Running setup.py install for psutil 18:38:52 INFO - building 'psutil._psutil_osx' extension 18:38:52 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 18:38:52 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 18:38:53 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 18:38:53 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 18:38:53 INFO - building 'psutil._psutil_posix' extension 18:38:53 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 18:38:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:38:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:38:53 INFO - ^ 18:38:53 INFO - 1 warning generated. 18:38:53 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 18:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:53 INFO - Successfully installed psutil 18:38:53 INFO - Cleaning up... 18:38:53 INFO - Return code: 0 18:38:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:38:53 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')]} 18:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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')]} 18:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:38:53 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 18:38:53 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 18:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:53 INFO - 'HOME': '/Users/cltbld', 18:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:53 INFO - 'LOGNAME': 'cltbld', 18:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:53 INFO - 'MOZ_NO_REMOTE': '1', 18:38:53 INFO - 'NO_EM_RESTART': '1', 18:38:53 INFO - 'PAGER': '/bin/cat', 18:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:53 INFO - 'PWD': '/builds/slave/test', 18:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:53 INFO - 'SHELL': '/bin/bash', 18:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:38:53 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:53 INFO - 'USER': 'cltbld', 18:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:53 INFO - 'XPC_FLAGS': '0x0', 18:38:53 INFO - 'XPC_SERVICE_NAME': '0', 18:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:38:56 INFO - Installing collected packages: mozsystemmonitor 18:38:56 INFO - Running setup.py install for mozsystemmonitor 18:38:56 INFO - Successfully installed mozsystemmonitor 18:38:56 INFO - Cleaning up... 18:38:56 INFO - Return code: 0 18:38:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:56 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:38:56 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 18:38:56 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 18:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:56 INFO - 'HOME': '/Users/cltbld', 18:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:56 INFO - 'LOGNAME': 'cltbld', 18:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:56 INFO - 'MOZ_NO_REMOTE': '1', 18:38:56 INFO - 'NO_EM_RESTART': '1', 18:38:56 INFO - 'PAGER': '/bin/cat', 18:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:56 INFO - 'PWD': '/builds/slave/test', 18:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:56 INFO - 'SHELL': '/bin/bash', 18:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:38:56 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:56 INFO - 'USER': 'cltbld', 18:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:56 INFO - 'XPC_FLAGS': '0x0', 18:38:56 INFO - 'XPC_SERVICE_NAME': '0', 18:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:56 INFO - Downloading/unpacking jsonschema==2.5.1 18:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:38:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:38:59 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 18:38:59 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 18:38:59 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 18:38:59 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 18:38:59 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 18:38:59 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 18:38:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:38:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:39:00 INFO - warning: no files found matching '*.txt' 18:39:00 INFO - no previously-included directories found matching 'build' 18:39:00 INFO - no previously-included directories found matching 'dist' 18:39:00 INFO - no previously-included directories found matching '.git*' 18:39:00 INFO - Installing collected packages: jsonschema, functools32 18:39:00 INFO - Running setup.py install for functools32 18:39:00 INFO - warning: no files found matching '*.txt' 18:39:00 INFO - no previously-included directories found matching 'build' 18:39:00 INFO - no previously-included directories found matching 'dist' 18:39:00 INFO - no previously-included directories found matching '.git*' 18:39:00 INFO - Successfully installed jsonschema functools32 18:39:00 INFO - Cleaning up... 18:39:00 INFO - Return code: 0 18:39:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:39:00 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')]} 18:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:00 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')]} 18:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:00 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:00 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 18:39:00 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 18:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:00 INFO - 'HOME': '/Users/cltbld', 18:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:00 INFO - 'LOGNAME': 'cltbld', 18:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:00 INFO - 'MOZ_NO_REMOTE': '1', 18:39:00 INFO - 'NO_EM_RESTART': '1', 18:39:00 INFO - 'PAGER': '/bin/cat', 18:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:00 INFO - 'PWD': '/builds/slave/test', 18:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:00 INFO - 'SHELL': '/bin/bash', 18:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:00 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:00 INFO - 'USER': 'cltbld', 18:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:00 INFO - 'XPC_FLAGS': '0x0', 18:39:00 INFO - 'XPC_SERVICE_NAME': '0', 18:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:39:00 INFO - Cleaning up... 18:39:00 INFO - Return code: 0 18:39:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:39:00 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')]} 18:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:00 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')]} 18:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:00 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:00 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 18:39:00 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 18:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:00 INFO - 'HOME': '/Users/cltbld', 18:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:00 INFO - 'LOGNAME': 'cltbld', 18:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:00 INFO - 'MOZ_NO_REMOTE': '1', 18:39:00 INFO - 'NO_EM_RESTART': '1', 18:39:00 INFO - 'PAGER': '/bin/cat', 18:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:00 INFO - 'PWD': '/builds/slave/test', 18:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:00 INFO - 'SHELL': '/bin/bash', 18:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:00 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:00 INFO - 'USER': 'cltbld', 18:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:00 INFO - 'XPC_FLAGS': '0x0', 18:39:00 INFO - 'XPC_SERVICE_NAME': '0', 18:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:39:00 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 18:39:00 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 18:39:00 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 18:39:00 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 18:39:00 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 18:39:00 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 18:39:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:39:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:39:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:39:04 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 18:39:04 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 18:39:04 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 18:39:04 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 18:39:04 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 18:39:04 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 18:39:04 INFO - Downloading docopt-0.6.1.tar.gz 18:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:39:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:39:04 INFO - Running setup.py install for blobuploader 18:39:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:39:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:39:04 INFO - Running setup.py install for requests 18:39:05 INFO - Running setup.py install for docopt 18:39:05 INFO - Successfully installed blobuploader requests docopt 18:39:05 INFO - Cleaning up... 18:39:05 INFO - Return code: 0 18:39:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:05 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:05 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 18:39:05 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 18:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:05 INFO - 'HOME': '/Users/cltbld', 18:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:05 INFO - 'LOGNAME': 'cltbld', 18:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:05 INFO - 'MOZ_NO_REMOTE': '1', 18:39:05 INFO - 'NO_EM_RESTART': '1', 18:39:05 INFO - 'PAGER': '/bin/cat', 18:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:05 INFO - 'PWD': '/builds/slave/test', 18:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:05 INFO - 'SHELL': '/bin/bash', 18:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:05 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:05 INFO - 'USER': 'cltbld', 18:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:05 INFO - 'XPC_FLAGS': '0x0', 18:39:05 INFO - 'XPC_SERVICE_NAME': '0', 18:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-QZB2aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-oooWaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-FikRFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-47z2BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-qxO_lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TDeEIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-cqmZ2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-8PQSS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-U_cFmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-qOV4dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-9m26tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-oXGSB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-_l2TzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-HpZDVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-rz6dnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-KxP1SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:07 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-tqWDfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:39:07 INFO - Running setup.py install for manifestparser 18:39:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:39:07 INFO - Running setup.py install for mozcrash 18:39:07 INFO - Running setup.py install for mozdebug 18:39:08 INFO - Running setup.py install for mozdevice 18:39:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Running setup.py install for mozfile 18:39:08 INFO - Running setup.py install for mozhttpd 18:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Running setup.py install for mozinfo 18:39:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Running setup.py install for mozInstall 18:39:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:39:08 INFO - Running setup.py install for mozleak 18:39:08 INFO - Running setup.py install for mozlog 18:39:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Running setup.py install for moznetwork 18:39:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Running setup.py install for mozprocess 18:39:09 INFO - Running setup.py install for mozprofile 18:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Running setup.py install for mozrunner 18:39:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:39:09 INFO - Running setup.py install for mozscreenshot 18:39:09 INFO - Running setup.py install for moztest 18:39:10 INFO - Running setup.py install for mozversion 18:39:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:39:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:39:10 INFO - Cleaning up... 18:39:10 INFO - Return code: 0 18:39:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:10 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:10 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 18:39:10 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 18:39:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:10 INFO - 'HOME': '/Users/cltbld', 18:39:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:10 INFO - 'LOGNAME': 'cltbld', 18:39:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:10 INFO - 'MOZ_NO_REMOTE': '1', 18:39:10 INFO - 'NO_EM_RESTART': '1', 18:39:10 INFO - 'PAGER': '/bin/cat', 18:39:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:10 INFO - 'PWD': '/builds/slave/test', 18:39:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:10 INFO - 'SHELL': '/bin/bash', 18:39:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:10 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:10 INFO - 'USER': 'cltbld', 18:39:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:10 INFO - 'XPC_FLAGS': '0x0', 18:39:10 INFO - 'XPC_SERVICE_NAME': '0', 18:39:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-vZ14j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:10 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)) 18:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-dMWhG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:10 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)) 18:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-3HCnUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:10 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)) 18:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-2DZOWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:10 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)) 18:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-yeZXeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ebRr_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-pue29R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-VKn938-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-n_opxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-uV9Dy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-vCWL6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-bZnb2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-gWuBZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:11 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)) 18:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-RZwzzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:12 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)) 18:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Dlw2KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:12 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)) 18:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-BNv9dX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-PPQBnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:12 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)) 18:39:12 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)) 18:39:12 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)) 18:39:12 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)) 18:39:12 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)) 18:39:12 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)) 18:39:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:39:12 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 18:39:12 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 18:39:12 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 18:39:12 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 18:39:12 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 18:39:12 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 18:39:14 INFO - Downloading blessings-1.6.tar.gz 18:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:39:15 INFO - Installing collected packages: blessings 18:39:15 INFO - Running setup.py install for blessings 18:39:15 INFO - Successfully installed blessings 18:39:15 INFO - Cleaning up... 18:39:15 INFO - Return code: 0 18:39:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:15 INFO - Reading from file tmpfile_stdout 18:39:15 INFO - Current package versions: 18:39:15 INFO - blessings == 1.6 18:39:15 INFO - blobuploader == 1.2.4 18:39:15 INFO - docopt == 0.6.1 18:39:15 INFO - functools32 == 3.2.3-2 18:39:15 INFO - jsonschema == 2.5.1 18:39:15 INFO - manifestparser == 1.1 18:39:15 INFO - mozInstall == 1.12 18:39:15 INFO - mozcrash == 1.0 18:39:15 INFO - mozdebug == 0.1 18:39:15 INFO - mozdevice == 0.48 18:39:15 INFO - mozfile == 1.2 18:39:15 INFO - mozhttpd == 0.7 18:39:15 INFO - mozinfo == 0.9 18:39:15 INFO - mozleak == 0.1 18:39:15 INFO - mozlog == 3.4 18:39:15 INFO - moznetwork == 0.27 18:39:15 INFO - mozprocess == 0.23 18:39:15 INFO - mozprofile == 0.28 18:39:15 INFO - mozrunner == 6.13 18:39:15 INFO - mozscreenshot == 0.1 18:39:15 INFO - mozsystemmonitor == 0.3 18:39:15 INFO - moztest == 0.8 18:39:15 INFO - mozversion == 1.4 18:39:15 INFO - psutil == 3.1.1 18:39:15 INFO - requests == 1.2.3 18:39:15 INFO - wsgiref == 0.1.2 18:39:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:15 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')]} 18:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:15 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')]} 18:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:15 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:15 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 18:39:15 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 18:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:15 INFO - 'HOME': '/Users/cltbld', 18:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:15 INFO - 'LOGNAME': 'cltbld', 18:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:15 INFO - 'MOZ_NO_REMOTE': '1', 18:39:15 INFO - 'NO_EM_RESTART': '1', 18:39:15 INFO - 'PAGER': '/bin/cat', 18:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:15 INFO - 'PWD': '/builds/slave/test', 18:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:15 INFO - 'SHELL': '/bin/bash', 18:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:15 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:15 INFO - 'USER': 'cltbld', 18:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:15 INFO - 'XPC_FLAGS': '0x0', 18:39:15 INFO - 'XPC_SERVICE_NAME': '0', 18:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 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)) 18:39:15 INFO - Cleaning up... 18:39:15 INFO - Return code: 0 18:39:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:39:15 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')]} 18:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:15 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')]} 18:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:15 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 0x107bbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3694cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, '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.dsA9OfNsJc/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.FFGYteJP0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 18:39:15 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 18:39:15 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 18:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:15 INFO - 'HOME': '/Users/cltbld', 18:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:15 INFO - 'LOGNAME': 'cltbld', 18:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:15 INFO - 'MOZ_NO_REMOTE': '1', 18:39:15 INFO - 'NO_EM_RESTART': '1', 18:39:15 INFO - 'PAGER': '/bin/cat', 18:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:15 INFO - 'PWD': '/builds/slave/test', 18:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:15 INFO - 'SHELL': '/bin/bash', 18:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:15 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:15 INFO - 'USER': 'cltbld', 18:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:15 INFO - 'XPC_FLAGS': '0x0', 18:39:15 INFO - 'XPC_SERVICE_NAME': '0', 18:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:39:16 INFO - Cleaning up... 18:39:16 INFO - Return code: 0 18:39:16 INFO - Running post-action listener: _resource_record_post_action 18:39:16 INFO - Running post-action listener: _start_resource_monitoring 18:39:16 INFO - Starting resource monitoring. 18:39:16 INFO - [mozharness: 2017-01-11 02:39:16.172645Z] Finished create-virtualenv step (success) 18:39:16 INFO - [mozharness: 2017-01-11 02:39:16.173639Z] Running install step. 18:39:16 INFO - Running pre-action listener: _resource_record_pre_action 18:39:16 INFO - Running main action method: install 18:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:16 INFO - Reading from file tmpfile_stdout 18:39:16 INFO - Detecting whether we're running mozinstall >=1.0... 18:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:39:16 INFO - Reading from file tmpfile_stdout 18:39:16 INFO - Output received: 18:39:16 INFO - Usage: mozinstall [options] installer 18:39:16 INFO - Options: 18:39:16 INFO - -h, --help show this help message and exit 18:39:16 INFO - -d DEST, --destination=DEST 18:39:16 INFO - Directory to install application into. [default: 18:39:16 INFO - "/builds/slave/test"] 18:39:16 INFO - --app=APP Application being installed. [default: firefox] 18:39:16 INFO - mkdir: /builds/slave/test/build/application 18:39:16 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'] 18:39:16 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 18:39:47 INFO - Reading from file tmpfile_stdout 18:39:47 INFO - Output received: 18:39:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:39:47 INFO - Running post-action listener: _resource_record_post_action 18:39:47 INFO - [mozharness: 2017-01-11 02:39:47.682610Z] Finished install step (success) 18:39:47 INFO - [mozharness: 2017-01-11 02:39:47.682749Z] Running run-tests step. 18:39:47 INFO - Running pre-action listener: _resource_record_pre_action 18:39:47 INFO - Running main action method: run_tests 18:39:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:47 INFO - 2017-01-10 18:39:47.697 screenresolution[1888:17838] starting screenresolution argv=screenresolution get 18:39:47 INFO - 2017-01-10 18:39:47.699 screenresolution[1888:17838] Display 0: 1600x1200x32@60 18:39:47 INFO - 2017-01-10 18:39:47.705 screenresolution[1889:17840] starting screenresolution argv=screenresolution list 18:39:47 INFO - Available Modes on Display 0 18:39:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:47 INFO - Intel Iris: 18:39:47 INFO - Chipset Model: Intel Iris 18:39:47 INFO - Type: GPU 18:39:47 INFO - Bus: Built-In 18:39:47 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:47 INFO - Vendor: Intel (0x8086) 18:39:47 INFO - Device ID: 0x0a2e 18:39:47 INFO - Revision ID: 0x0009 18:39:47 INFO - Displays: 18:39:47 INFO - Display: 18:39:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:47 INFO - Display Serial Number: 1600x1200 60 18:39:47 INFO - Main Display: Yes 18:39:47 INFO - Mirror: Off 18:39:47 INFO - Online: Yes 18:39:47 INFO - Rotation: Supported 18:39:47 INFO - Return code: 0 18:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:39:47 INFO - Python 2.7.3 18:39:47 INFO - Return code: 0 18:39:47 INFO - grabbing minidump binary from tooltool 18:39: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')]} 18:39:47 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 0x1079d3d30>, '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 0x107839db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:39:47 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 18:39:47 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 18:39:47 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 18:39:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:39:48 INFO - Return code: 0 18:39:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:39:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:39:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:39:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:39:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:39:48 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:39:48 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dsA9OfNsJc/Render', 18:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:48 INFO - 'HOME': '/Users/cltbld', 18:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:48 INFO - 'LOGNAME': 'cltbld', 18:39:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:48 INFO - 'MOZ_NO_REMOTE': '1', 18:39:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:48 INFO - 'NO_EM_RESTART': '1', 18:39:48 INFO - 'PAGER': '/bin/cat', 18:39:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:48 INFO - 'PWD': '/builds/slave/test', 18:39:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:48 INFO - 'SHELL': '/bin/bash', 18:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FFGYteJP0X/Listeners', 18:39:48 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 18:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:48 INFO - 'USER': 'cltbld', 18:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:48 INFO - 'XPC_FLAGS': '0x0', 18:39:48 INFO - 'XPC_SERVICE_NAME': '0', 18:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:39:48 INFO - mozversion application_buildid: 20170110173105 18:39:48 INFO - mozversion application_changeset: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 18:39:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:39:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:39:48 INFO - mozversion application_name: Firefox 18:39:48 INFO - mozversion application_remotingname: firefox-dev 18:39:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:39:48 INFO - mozversion application_vendor: Mozilla 18:39:48 INFO - mozversion application_version: 52.0a2 18:39:48 INFO - mozversion platform_buildid: 20170110173105 18:39:48 INFO - mozversion platform_changeset: 5cf9f0f65d201fc4631530c8f00e3a14887c1ad1 18:39:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:39:48 INFO - mozversion platform_version: 52.0a2 18:39:48 INFO - using testdate: 1484102388 18:39:48 INFO - actual date: 1484102388 18:39:48 INFO - starting webserver on 'localhost:53230' 18:39:48 INFO - SUITE-START | Running 2 tests 18:39:48 INFO - TEST-START | dromaeo_css 18:39:48 INFO - operating with platform_type : mac_ 18:39:48 INFO - Initialising browser for dromaeo_css test... 18:39:48 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpVwN5Ns/profile http://localhost:53230/getInfo.html) 18:39:51 INFO - PROCESS | 1899 | 2017-01-10 18:39:51.330 plugin-container[1901:18019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 18:39:51 INFO - PROCESS | 1899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:51 INFO - PROCESS | 1899 | 2017-01-10 18:39:51.333 plugin-container[1901:18019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 18:39:51 INFO - PROCESS | 1899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:51 INFO - PROCESS | 1899 | 2017-01-10 18:39:51.333 plugin-container[1901:18019] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:39:51 INFO - PROCESS | 1899 | __metrics Screen width/height:1600/1200 18:39:51 INFO - PROCESS | 1899 | colorDepth:24 18:39:51 INFO - PROCESS | 1899 | Browser inner width/height: 1024/689 18:39:51 INFO - PROCESS | 1899 | __metrics 18:39:51 ERROR - PROCESS | 1899 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 18:39:52 INFO - TEST-INFO | 1899: exit 0 18:39:52 INFO - Browser initialized. 18:39:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:39:52 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpVwN5Ns/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:54 INFO - PROCESS | 1902 | 2017-01-10 18:39:54.871 plugin-container[1903:18154] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 18:39:54 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:54 INFO - PROCESS | 1902 | 2017-01-10 18:39:54.872 plugin-container[1903:18154] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 18:39:54 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:39:54 INFO - PROCESS | 1902 | 2017-01-10 18:39:54.872 plugin-container[1903:18154] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:40:04 INFO - PROCESS | 1902 | RSS: Main: 228741120 18:40:04 INFO - PROCESS | 1902 | 18:43:07 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53230/tests/dromaeo/cssquery-ext.html) 18:43:09 INFO - PROCESS | 1902 | RSS: Main: 233820160 18:43:09 INFO - PROCESS | 1902 | 18:46:11 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-ext.html (next: http://localhost:53230/tests/dromaeo/cssquery-jquery.html) 18:46:12 INFO - PROCESS | 1902 | RSS: Main: 243359744 18:46:12 INFO - PROCESS | 1902 | 18:49:14 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53230/tests/dromaeo/cssquery-mootools.html) 18:49:14 INFO - PROCESS | 1902 | RSS: Main: 246194176 18:49:14 INFO - PROCESS | 1902 | 18:52:16 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53230/tests/dromaeo/cssquery-prototype.html) 18:52:17 INFO - PROCESS | 1902 | RSS: Main: 246415360 18:52:17 INFO - PROCESS | 1902 | 18:55:20 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53230/tests/dromaeo/cssquery-yui.html) 18:55:20 INFO - PROCESS | 1902 | RSS: Main: 250359808 18:55:20 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:53230/tests/dromaeo/cssquery-yui.html (next: http://localhost:53230/tests/dromaeo/cssquery-dojo.html) 18:58:23 INFO - PROCESS | 1902 | RSS: Main: 251744256 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | __start_tp_report 18:58:23 INFO - PROCESS | 1902 | _x_x_mozilla_page_load 18:58:23 INFO - PROCESS | 1902 | _x_x_mozilla_page_load_details 18:58:23 INFO - PROCESS | 1902 | |i|pagename|runs| 18:58:23 INFO - PROCESS | 1902 | |0;dojo.html;3441.2257242120522;3459.913502162447;3462.965370346296;3479.2171761353698;3492.70312023478;5863.41365863414;5903.645781253123;5916.4083591640765;5925.31858836234;5938;5854.736536855841;5912.763489460416;5939.643621382709;5944.910826337608;5955.612885162462;4511.52628973958;5322.441143679906;5330.200469929522;5367.409584945653;5381.623286369956;5295.629305948585;5310.654807437504;5405.108157154064;5406.216098665691;5422.864428389283;5215.8174463893965;5267.815626453094;5284.233786101032;5319.069162896516;5365.758540865631;5247.501487358695;5258.447862974371;5301.973490132525;5336.332958380202;5351.759170837322;5207.609429292818;5234.7120908350025;5276.947230527722;5306.495882891119;5332.68003919766;5674.602777805555;5809.419058094199;5867.76528938842;5876.4123587641325;5945.91081133779;6140.539459540552;6254;6268.65522396268;6275.717592708339;6299.905501417483;4759.643501602027;4918.893249018942;5663.801766938137;5985.042393217108;6223.253209614875;5805.129230615357;5893.14549390336;5995.880082398371;6009.609375390673;6024.246969128815;6300.086487459293;6315.652639104802;6338.683065846689;6396.968015159894;6399.9360006400275;6234.625922444668;6238.906416403757;6248.9062664060075;6251.374862513712;6251.749930002749;6318.778842740482;6336.144620476177;6344.58760180596;6350.4602108820345;6367.140436041168;6154.292256390483;6206.689665516707;6226.875462490725;6228.190335256388;6259.374062593795;6207.379262073847;6219.129321894939;6252.843678908063;6281.2462504498835;6295.087212354275;5015.648904576645;5033.219850923039;5047.949520504748;5051.217061355495;5052.216906379517;4448.065906159743;4456.086502267028;4482.484514280899;4484.529124441952;4539.546045395434;5150.660828184699;5986.610870293417;5986.73059712314;6092.086187071886;6102.72537735803;6201.720922558496;6298.086777417248;6299.574663754487;6300.369963003663;6341.968290158612;5856.297244330707;5950.166976623276;5969.283685957713;5984.581079324493;6034.969825150846;5522.5581953443625;5558.6942718150085;5564.360098588633;5571.470710282517;5603.971980140073;6228.6574238416415;6270.435660790551;6387.808365749035;6392.232932048184;6422.6467544285515;6333.145025421509;6343.5876668016435;6347.428731414195;6356;6379.712912918837;6497.350264973465;6535.2811190768025;6535.967320163369;6538.346165383614;6544.051112588743;6084.939150608438;6086.1175129606845;6152.600080994721;6229.626222426669;6278.591891527036;6158.722857471335;6184.103305020837;6207.627542347293;6217.222847144107;6223.222097237845;5966.194563734014;5968.850778730392;5995.970020149872;6037.577369584263;6040.969795150996;6013.548983826318;6034.185384972972;6109.951287404173;6129.8784197560135;6157.415045570664;5282.996230716151;5763.971180144073;6014.548908831767;6106.45041946227;6182.350853160354;6239.282482514569;6265.8120256392385;6269.404406581368;6270.843228919313;6286.654234017173;5624.015797235549;5667.20659107717;6140.263168419819;6151.907721384094;6156.846078847885 18:58:23 INFO - PROCESS | 1902 | |1;ext.html;463.2264118921403;483.6010291509505;512.3825789923143;518.4323166133083;525.3197109742882;35134.472982905274;41607.95980100499;41713.165736685245;41751.37372939409;41894.37158442618;33201.16997075074;40648.39027414591;42873;42946.570534294646;43027.13945721084;8083.232092951161;9391.6712915048;9441.150296473315;9514.57184426699;9534.80930381392;9461;9497.477638729868;9514.952425237898;9547.80904381912;9556.856647150298;9491.19324857388;9492.477913714778;9562.760930976712;9599.184069354078;9643.90356096437;9421.670241541548;9482.383645063082;9490.098440648128;9573.760655983586;9608.183304419133;9363.953180234123;9411.482368469731;9495.38280011797;9496.382735122195;9510.09654082861;39740.60259397427;40092.39861402052;40159.79920100381;40271.59049433256;40308.59691403078;3175.9682403176134;3262.6411094779555;3434.9828250858836;3480;3728.440733889911;29735.40529189404;30273.69726302731;30445.69554304473;30540.694593054228;30875;3073.89241376553;3204.006757905057;3215.5337476066074;3245.578074850278;3277.4919887417486;518047;518523;520880;523479;524435.9999999962;62727;63133.68433157805;63383.6830815843;63473;63561.68219158875;65119;65424.672876636265;66355.33644663588;66380;66585;16375.344986200656;16411.42560010394;16628.33486660544;16638.25127869249;16646.334146634243;12168;12559.183653062522;12675.556355527697;12677.809832852514;12691.873081269254;17970.561695273682;18785.966771827407;19283.93938333405;19387.612247754965;19403.80596194048;2684.5704687249945;3246.788958717676;7403.81587431709;7518.436117291225;7602.517509085786;866.5927014303268;873.5108339329995;911.1207684584408;931.5156118818177;939.6379367654367;30256.84871575628;30465.238369040508;30477.390452190833;30519.847400762854;30826.53760193599;1012.964546240878;1054.915606751458;1056.5245639462273;1057.5082586597268;1102.2890235798027;14617.488387906586;15357.616059598377;15765.211739412984;15818.446354377542;16019;616790;619219;622245;624059;626146;47714;56363.71818140965;57039.429605704245;57678.711606442535;57703.71148144316;1299.2918859221747;1348.8853447457077;1354.695193581456;1390.65928847431;1398.8041674165624;28738.706758195513;29633;29636.110916672533;30237;30246.84876575603;38258;43059.9235019127;51181.23228151506;52800;52879;623676;625286;630436;631546;631580;21936.780632193473;26393.340166495993;26415.60376594391;26424.8678756605;26471.470570588866;41220.79389603033;41794.58205418028;44986.77506612446;45171.548284516735;45586.54413455823;37653.81173094117;37720.62279377171;38105.42841857319;38126.80936595299;38698;37774.62225377821;37986.43020354641;37990.62009379871;38622.6137738619;38775;37021.62978370182;37507.43738843865;37581.4362784553;37674.43488347622;37927.05182370352;32073.198170045936;34387.312253755284;34456.96629101131;34501;34675.306493870485;25329.493410131327;25456.745432546177;25554.872225638752;25970.87014564915;26216.674248748743 18:58:23 INFO - PROCESS | 1902 | |2;jquery.html;306.995395069074;368.9981550092249;376.4071587250081;382.64414094891737;401.9849879055385;18493;19344.129514171862;19708.310209142684;20048.89975550122;20244;19071.523211919673;19238.32665856695;19511;19858.304959326426;20003.799962000376;5647.040003199466;5686.232358631583;5939.168516407706;5944.405559444064;5955.464008239258;5685.687287199203;5812.738426770784;5840.182374467578;5850.648961062328;5870.442307980757;5573.860653483675;5742.023855944479;5771.82684519463;5814.98237808384;5829.067349224125;5711.400302968212;5786.1610066540325;5790.826275211751;5833.649981001154;5847.006008978484;5020.246962955586;5037.19404895215;5094.79620815167;5101.285819985205;5110.361204849394;24595.63106553403;24748;24760.25719228426;24886.37784055395;24996.250112496655;1934.264979307868;2203.7575866654656;2211.9115235390564;2257.266388423769;2979.6424429068434;9986.600535978552;10224.897751022543;14224.431022759078;14476;14924.850751492564;1727.1105380728934;1843.3732530939544;1861.1066687989705;1861.3950466098572;1889.6409682160345;123839;125235.74764252332;126678.09982850264;128085;130894;11231.326120432801;11602.593909213138;11869.940650296694;11894.286342819458;11927.343996080126;14433.566992990227;14497.565073047614;14521.346539405753;14531.636709082357;14533.491327803476;7894.250046245597;8205.876911846328;8391.790205244795;8428.157184281645;8559.828803423896;6913.702812042332;8351.331893448509;8379;8496.320294376435;8514.957425212959;4321.546237645065;4506.301523263899;4530.343100250511;4540.818367265272;4543.750093744876;1866.1975350599373;1914.731937528747;1916.6166766646727;1918.4340619516997;1919.4529559075875;692.2800287700835;707.4093132234642;744.3449764207463;794.8450052239873;918.0727465260002;19396;19915.250002498597;20317.49206269855;20596.58806823855;20808.27171049006;793.5357815677893;846.0017179727857;944.4474982135528;970.5001924009141;1015.5988384588046;9290.860637090449;10092.899071009197;10873.891261087289;11057.72355691098;11070.778584428428;587970.0601496965;588802.0559897174;595964;599545;601229;108092.45953770338;108751;118912;130644;141554;918.6325469812128;919.8252332056887;931.4411353188033;955.4267439536222;989.1888651306004;18165.909170454594;18222.72665909986;18389.793224813107;18640.627187456445;19724.704129438356;55204;55488;56195.59511012257;56775.71612141913;56856.43143568511;69548.26129346807;78951;80272.59863700643;80598.19401806142;82083.5895820517;6015.39846015395;8041.597920104088;8043.19568043191;8231.888695026095;8403.537805420761;12192.146549741434;12653.810192847299;12682.936585317015;12991.870081299445;13011.934940325238;10941.234113612289;11091.057260132979;11236.887631123911;11257.324560526067;11295.265807722471;10836.9163083691;10974.945125274322;10991.670249892515;11056.447177640925;11110.222284440011;9758.853617195606;11143.275687080313;11341.773164536828;11344.092472602008;11432.371219583003;7485.139208991036;8058.355331573577;8110.148434414302;8304.70933517324;8385.622646981023;10196.388216706724;10242.283040187413;10855.837162442413;10992.340459572451;11218.943905280748 18:58:23 INFO - PROCESS | 1902 | |3;mootools.html;1299.1944994103653;1442.1131004432277;1577.4005877766447;1752.2552915011117;1775.8490528305094;7502.32479076883;7732.420068494871;7993.1607181246;8443.240108390244;8457.746267611974;7327.890081648779;7957.960210198956;8116.797080073001;8275.006999160109;8359.164083591639;2046.1201683276186;2269.716285464317;2306.538692261546;2339.146211632759;2346.8122550195944;2313.178821518362;2338.169949667869;2344.800691941187;2352.9411764705933;2356.6229403295474;2255.7180352455944;2308.2382813671447;2328.2549584133085;2341.7072865891764;2348.6711860339565;2297.494551198742;2309.0186670665066;2327.2087490253225;2329.580675478413;2339.4853132310864;2329.2779238436137;2347.1550241912896;2351.2123438648073;2351.5061837014246;2355.787979081891;8155.306798922039;8315.168483151698;8330.83338333236;8337.583120843934;8340.791480212982;5025.497450254982;5175.327207463043;5191.818286359997;5193.9740301298625;5208.6093542984045;3293.670632936711;3415.077928959192;3501.28223714138;3563.893083207508;3582.9283414331567;1021.6526381030485;1050.4170185547018;1089.1069323155016;1099.1536516882036;1099.571167244775;10858.23992320538;10861.022507974241;10941.23411361205;11000.449977501174;11012.229143959847;4366.43236379275;4466.57567531084;4486.327050942385;4609.695152423788;4677.64917631179;7428.368588670023;7887.645055972495;8872.378933474723;8891.422057566368;9140.451572905647;5148.922766158511;5169.405518365361;5178.300929374486;5182.067227898939;5187.170052791611;4208.747475151501;4494.797734101973;4756.429228492603;4757.381540399726;4771.73755443447;3181;3310.1724568857785;3763.8682646107254;3841.961580384216;3977.900552486211;1253.4923356040817;1271.199144538953;1275.9553415630407;1281.7564662714055;1294.3204817470714;720.2365492577779;870.2602787630464;882.069416765306;886.7206829848577;889.3330002498126;5504.9449505504435;5514.200440936122;5514.7187423482965;5518.061929471999;5531;442.76975972494125;443.30622575668974;443.3748414735208;955.0974329258784;991.8313886639289;3134.5925029745986;3567.4470457079196;3646.690031347312;3651.6348365163803;3749.8500059997295;9781.510924453749;10193.949030254802;10199.439030853226;10214.693559193236;10220.846687299696;10000.699979000641;10083.14293285079;10113.595456181818;10184.439855807854;10229;6730.32696730323;7696.6536505857375;7852.803679907934;7856.921430785734;7870.645820937957;5201.193814958688;5250.711210883438;5318.202269659582;5413.648112872651;5498.367687715804;9112.772180695536;9156.450612963243;9240.260779137787;9419.481928493999;9532.904670953201;9433.952830235805;9462.574184161867;9491.57287922059;9501.524923753923;9604.9039509604;6415.1981002374705;7087.893681594677;7128.180259270043;7265.9273407265255;7267.127944646676;7923.643436045277;7987.760367188994;7988.600569971362;8005.199480052182;8016.7194148204535;7040.5071644984355;7192.532485388642;7195.676194571152;7216.89174662391;7236.819079523054;6959.304069593;6991.161060672548;7045.753398631024;7055.717771289091;7138.714451421885;6639.236487803964;6649.667516624053;6727.562708423937;6737.326267373223;6738.764143254963;4669.229577119873;4694.507076756891;4739.597134243628;4751.192297309397;4769.642276829182;4126.7523948563185;4574.81700731967;4645.117427688729;4778.1638213311835;4874.341963834979 18:58:23 INFO - PROCESS | 1902 | |4;prototype.html;272.45916855042736;308.19868342309996;309.31949710636593;314.9763767717419;316.0455424617656;4690.57784799368;5106;5140.7172605506685;5504.807211817147;5551.1126217086185;4900.58345040672;4984.87537811554;5292.497212764791;5384.865378365543;5520.1443776214755;3971.62269584389;3977.6618987386037;4119.691023173259;4165.229432554987;4186.832526698929;3460.723142148622;4170.603792639709;4173.9582604173875;4180.1430706705205;4206.3920369371035;3650.945235821465;3707.6292370762976;4121.216968775923;4163.437935878663;4193.517745459266;3230.8869189578486;3446.3451944130784;3955.9318983874336;4137.379393091061;4200.726952748077;3547.2728090741334;3550.467429885512;3555.4889172101753;3567.571891373052;3583.5341405617105;5296.861174847426;5335.733213339357;5368.543673787733;5376.946230537684;5431.158170483582;3787.867424640124;4637.86086417408;4677.549959512562;4730.787114579852;4731.5268473152755;5658.547316214693;5726.914096288559;5766.682832444214;5804.548862784304;5821.5342772578515;3290.8683652653867;3909.63162892988;3929.5677475477673;3935.4687117239378;3945.842166313344;5830.3586605473365;6081.5742897996715;6084.634921904656;6149.538784591174;6161.6303021818385;3406.9488957665653;3647.4133751818044;3999.460072890123;3999.8800035998966;4028.576999415078;3365.6129545102135;3465.0297916583395;3711.6473934976134;3897.649211570972;4071.796410179479;3806.6764325032127;4175.874723758292;4194.6853985951175;4724.433068031858;4756.595689366373;5119.590432765444;5569.663280812576;5654;5900.911486327708;5919.230500034968;1152.6772503698978;1180.7697498987786;1246.82798169722;1311.4229738914846;1326.9601911942657;708.2917082917083;851.6891334662804;866.4021824940771;882.6734108379941;903.9514163570358;4629.791659375336;4773.71357718538;5072.492750724898;5143.71709555978;5409.702466364309;5056.457780377021;5496.725163741797;5536.612437129363;5611.3266408031295;6019.568845757964;4899.510048995072;5383.865403364869;5514.310711161105;5530.474604912527;5535.363433205152;2846.4524250795835;2880.7839412043763;2935.708288353118;2961.956250187407;2980.5678176664264;5512.39363669996;5737.598368114275;6004.528890421874;6011.6693581853415;6014.338422773491;4947.876303092451;5083.339165908482;5514.696691681996;5737.340205876294;5839.89763685073;5482.5613950883835;5745.913811292834;5948.286205655262;5984.611000284967;6006.609570377911;4581.473130590025;4951.851444456672;5526.861828454321;5757.481826635623;5849.356570777126;5064.797408103635;5382.36264230532;5457.945420545744;5739.426057394227;5746.741396637245;4878.682885612424;4933.199382225534;5309.65487243328;5370.650907690988;5954.225950626565;3293.5059741039036;3680.9447858282683;3814.332491813977;3823.770573765583;3855.36386496225;6008.849778755566;6010.3088144863905;6047.697615119138;6054.39456054391;6120.663363515049;5810.331811841692;5894.351621321565;5913.965056115248;5925.874083924041;5941.643501389931;5146.819861304836;5235.214717792362;5749;5812.360640329476;5856.9121463178935;5103.362079740033;5442.775375540472;5734.283214598176;5754.712264386848;5764.855878603068;4962.680046930748;4982.352294201731;5724.398938111605;5879.617824841372;5944.524438044859;5736.569757268138;5783.363829978614;5815.3603103657715;5828.717682109929;5837.970810145922 18:58:23 INFO - PROCESS | 1902 | |5;yui.html;680.874038302914;691.7475121580622;726.6729971512821;745.6569977810207;761.3034073822453;13957.860421395782;14483.71032579349;14562.490312839052;14569.834413246954;14585.854141458582;12791.232526048443;12824.871751282484;12829.679258018554;12891.162074465154;12963.157394769358;643.7972038807773;668.9665516724168;788.4126325887222;816.5264146794844;822.1737154160061;808.2079562029215;818.6561644109488;819.6188772220916;819.6721311475397;823.3742355809568;867.8828358171661;868.0148031983713;869.7564681889081;871.860502319629;872.5375550957971;841.3269384492412;860.3117505995178;863.5854789700916;866.0170706248422;866.1598249697784;613.8864310102646;632.7627139822566;638.7157714816908;643.3212960326854;645.370763505583;13321.40053697586;13613.455461781517;13672.794908076386;13728.519501817489;14309.069910455892;13102.344882755824;13138.934305328508;13184.208947463183;13250.271235082068;13375.866241337559;10669.786604267949;10923.071538919201;10994.395308258043;11041.613543525938;11354.886451135466;3478.095695119262;3621.9275614487824;3682.558093028827;3686.4101743721144;3695.9630403695887;86707;89054.21891562133;89888.55055724745;90966.54516727374;91986.70033249156;10287.177025837915;10425.478726063819;10460.581576736997;10468.581256749645;10510;20410.387688369374;20486.873221972637;20621.793782061988;20711.689324660143;20855.165793368094;6679.599224046573;7115.644217789089;7297.927020729725;7426.220246874109;7447.44144189188;3589.94615080774;3748.8200656268064;4116.876493705194;4227.154569086195;4305.268104422255;3334.383139119222;3427.1946092668204;3462.688358047788;3470.687638112582;3483.5645544306963;617.5245061302771;628.0673200297555;628.3904612525843;630.3979699387074;643.6170478565247;563.9802606908738;565.6634302589953;605.9363766804511;650.1125963060389;657.7040331850686;8630.266427353745;8929.508877011827;9107.316951228684;9228.492432916253;9267.907320926839;621.5586933277333;640.4364159539575;640.8077576726963;645.3836586060302;652.7878439507084;3846.3268927937497;4231.809568569422;4379.627731642846;4415.536368681306;4480.820767169342;100881.43985146396;112999.43500282611;113124.43437782758;117147.82852171539;118769.81230187761;80761;82586;100743.49628251811;105029;107323.8535229291;78710.73537278503;79656.6017169922;90483.54758226298;92408.53795731113;94506.05493944972;8273.669053237803;8591.785205369915;8718.60766265507;8757.824843503222;8958.552072396484;46634;47310;47967.760161198974;58818;59212.703936480044;50507;56601.15098273447;57661.55846103713;67607.3239267601;70587.64706176436;9964.551595178089;10076.949615251877;11811.173217874666;12031;12248.326342051272;13340.332983350987;13720.588382348546;13726.86273137256;13847.861521384657;14007.509737159151;12561.937190314355;12562.37188140571;12615.873841261837;12640.431180596712;12807.167534110626;12786.232826030468;12831.230126192459;12860.935695321463;12917.870821291666;13042.54351097707;9091.454512729257;12120.333381664093;12247.387630618256;12312.630621081382;12481.625551233477;10542.15662746963;10628.149748020294;10668.359898406121;10836.566537338418;11145.108391328837;9206.597646261358;10625.734356641145;10890.455477226265;10950.835737464104;10951.726206844893 18:58:23 INFO - PROCESS | 1902 | __end_tp_report 18:58:23 INFO - PROCESS | 1902 | __start_cc_report 18:58:23 INFO - PROCESS | 1902 | _x_x_mozilla_cycle_collect,369 18:58:23 INFO - PROCESS | 1902 | __end_cc_report 18:58:23 INFO - PROCESS | 1902 | __startTimestamp1484103503637__endTimestamp 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | ------- Summary: start ------- 18:58:23 INFO - PROCESS | 1902 | Number of tests: 6 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#0] dojo.html Cycles:165 Average:5806.47 Median:6013.55 stddev:642.39 (10.7%) stddev-sans-first:616.98 18:58:23 INFO - PROCESS | 1902 | Values: 3441.2 3459.9 3463.0 3479.2 3492.7 5863.4 5903.6 5916.4 5925.3 5938.0 5854.7 5912.8 5939.6 5944.9 5955.6 4511.5 5322.4 5330.2 5367.4 5381.6 5295.6 5310.7 5405.1 5406.2 5422.9 5215.8 5267.8 5284.2 5319.1 5365.8 5247.5 5258.4 5302.0 5336.3 5351.8 5207.6 5234.7 5276.9 5306.5 5332.7 5674.6 5809.4 5867.8 5876.4 5945.9 6140.5 6254.0 6268.7 6275.7 6299.9 4759.6 4918.9 5663.8 5985.0 6223.3 5805.1 5893.1 5995.9 6009.6 6024.2 6300.1 6315.7 6338.7 6397.0 6399.9 6234.6 6238.9 6248.9 6251.4 6251.7 6318.8 6336.1 6344.6 6350.5 6367.1 6154.3 6206.7 6226.9 6228.2 6259.4 6207.4 6219.1 6252.8 6281.2 6295.1 5015.6 5033.2 5047.9 5051.2 5052.2 4448.1 4456.1 4482.5 4484.5 4539.5 5150.7 5986.6 5986.7 6092.1 6102.7 6201.7 6298.1 6299.6 6300.4 6342.0 5856.3 5950.2 5969.3 5984.6 6035.0 5522.6 5558.7 5564.4 5571.5 5604.0 6228.7 6270.4 6387.8 6392.2 6422.6 6333.1 6343.6 6347.4 6356.0 6379.7 6497.4 6535.3 6536.0 6538.3 6544.1 6084.9 6086.1 6152.6 6229.6 6278.6 6158.7 6184.1 6207.6 6217.2 6223.2 5966.2 5968.9 5996.0 6037.6 6041.0 6013.5 6034.2 6110.0 6129.9 6157.4 5283.0 5764.0 6014.5 6106.5 6182.4 6239.3 6265.8 6269.4 6270.8 6286.7 5624.0 5667.2 6140.3 6151.9 6156.8 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#1] ext.html Cycles:180 Average:71802.02 Median:39916.50 stddev:158475.16 (397.0%) stddev-sans-first:158829.21 18:58:23 INFO - PROCESS | 1902 | Values: 463.2 483.6 512.4 518.4 525.3 35134.5 41608.0 41713.2 41751.4 41894.4 33201.2 40648.4 42873.0 42946.6 43027.1 8083.2 9391.7 9441.2 9514.6 9534.8 9461.0 9497.5 9515.0 9547.8 9556.9 9491.2 9492.5 9562.8 9599.2 9643.9 9421.7 9482.4 9490.1 9573.8 9608.2 9364.0 9411.5 9495.4 9496.4 9510.1 39740.6 40092.4 40159.8 40271.6 40308.6 3176.0 3262.6 3435.0 3480.0 3728.4 29735.4 30273.7 30445.7 30540.7 30875.0 3073.9 3204.0 3215.5 3245.6 3277.5 518047.0 518523.0 520880.0 523479.0 524436.0 62727.0 63133.7 63383.7 63473.0 63561.7 65119.0 65424.7 66355.3 66380.0 66585.0 16375.3 16411.4 16628.3 16638.3 16646.3 12168.0 12559.2 12675.6 12677.8 12691.9 17970.6 18786.0 19283.9 19387.6 19403.8 2684.6 3246.8 7403.8 7518.4 7602.5 866.6 873.5 911.1 931.5 939.6 30256.8 30465.2 30477.4 30519.8 30826.5 1013.0 1054.9 1056.5 1057.5 1102.3 14617.5 15357.6 15765.2 15818.4 16019.0 616790.0 619219.0 622245.0 624059.0 626146.0 47714.0 56363.7 57039.4 57678.7 57703.7 1299.3 1348.9 1354.7 1390.7 1398.8 28738.7 29633.0 29636.1 30237.0 30246.8 38258.0 43059.9 51181.2 52800.0 52879.0 623676.0 625286.0 630436.0 631546.0 631580.0 21936.8 26393.3 26415.6 26424.9 26471.5 41220.8 41794.6 44986.8 45171.5 45586.5 37653.8 37720.6 38105.4 38126.8 38698.0 37774.6 37986.4 37990.6 38622.6 38775.0 37021.6 37507.4 37581.4 37674.4 37927.1 32073.2 34387.3 34457.0 34501.0 34675.3 25329.5 25456.7 25554.9 25970.9 26216.7 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#2] jquery.html Cycles:180 Average:35012.27 Median:24941.31 stddev:99411.76 (398.6%) stddev-sans-first:99656.48 18:58:23 INFO - PROCESS | 1902 | Values: 307.0 369.0 376.4 382.6 402.0 18493.0 19344.1 19708.3 20048.9 20244.0 19071.5 19238.3 19511.0 19858.3 20003.8 5647.0 5686.2 5939.2 5944.4 5955.5 5685.7 5812.7 5840.2 5850.6 5870.4 5573.9 5742.0 5771.8 5815.0 5829.1 5711.4 5786.2 5790.8 5833.6 5847.0 5020.2 5037.2 5094.8 5101.3 5110.4 24595.6 24748.0 24760.3 24886.4 24996.3 1934.3 2203.8 2211.9 2257.3 2979.6 9986.6 10224.9 14224.4 14476.0 14924.9 1727.1 1843.4 1861.1 1861.4 1889.6 123839.0 125235.7 126678.1 128085.0 130894.0 11231.3 11602.6 11869.9 11894.3 11927.3 14433.6 14497.6 14521.3 14531.6 14533.5 7894.3 8205.9 8391.8 8428.2 8559.8 6913.7 8351.3 8379.0 8496.3 8515.0 4321.5 4506.3 4530.3 4540.8 4543.8 1866.2 1914.7 1916.6 1918.4 1919.5 692.3 707.4 744.3 794.8 918.1 19396.0 19915.3 20317.5 20596.6 20808.3 793.5 846.0 944.4 970.5 1015.6 9290.9 10092.9 10873.9 11057.7 11070.8 587970.1 588802.1 595964.0 599545.0 601229.0 108092.5 108751.0 118912.0 130644.0 141554.0 918.6 919.8 931.4 955.4 989.2 18165.9 18222.7 18389.8 18640.6 19724.7 55204.0 55488.0 56195.6 56775.7 56856.4 69548.3 78951.0 80272.6 80598.2 82083.6 6015.4 8041.6 8043.2 8231.9 8403.5 12192.1 12653.8 12682.9 12991.9 13011.9 10941.2 11091.1 11236.9 11257.3 11295.3 10836.9 10974.9 10991.7 11056.4 11110.2 9758.9 11143.3 11341.8 11344.1 11432.4 7485.1 8058.4 8110.1 8304.7 8385.6 10196.4 10242.3 10855.8 10992.3 11218.9 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#3] mootools.html Cycles:180 Average:5376.37 Median:4826.25 stddev:2970.36 (61.5%) stddev-sans-first:2962.89 18:58:23 INFO - PROCESS | 1902 | Values: 1299.2 1442.1 1577.4 1752.3 1775.8 7502.3 7732.4 7993.2 8443.2 8457.7 7327.9 7958.0 8116.8 8275.0 8359.2 2046.1 2269.7 2306.5 2339.1 2346.8 2313.2 2338.2 2344.8 2352.9 2356.6 2255.7 2308.2 2328.3 2341.7 2348.7 2297.5 2309.0 2327.2 2329.6 2339.5 2329.3 2347.2 2351.2 2351.5 2355.8 8155.3 8315.2 8330.8 8337.6 8340.8 5025.5 5175.3 5191.8 5194.0 5208.6 3293.7 3415.1 3501.3 3563.9 3582.9 1021.7 1050.4 1089.1 1099.2 1099.6 10858.2 10861.0 10941.2 11000.4 11012.2 4366.4 4466.6 4486.3 4609.7 4677.6 7428.4 7887.6 8872.4 8891.4 9140.5 5148.9 5169.4 5178.3 5182.1 5187.2 4208.7 4494.8 4756.4 4757.4 4771.7 3181.0 3310.2 3763.9 3842.0 3977.9 1253.5 1271.2 1276.0 1281.8 1294.3 720.2 870.3 882.1 886.7 889.3 5504.9 5514.2 5514.7 5518.1 5531.0 442.8 443.3 443.4 955.1 991.8 3134.6 3567.4 3646.7 3651.6 3749.9 9781.5 10193.9 10199.4 10214.7 10220.8 10000.7 10083.1 10113.6 10184.4 10229.0 6730.3 7696.7 7852.8 7856.9 7870.6 5201.2 5250.7 5318.2 5413.6 5498.4 9112.8 9156.5 9240.3 9419.5 9532.9 9434.0 9462.6 9491.6 9501.5 9604.9 6415.2 7087.9 7128.2 7265.9 7267.1 7923.6 7987.8 7988.6 8005.2 8016.7 7040.5 7192.5 7195.7 7216.9 7236.8 6959.3 6991.2 7045.8 7055.7 7138.7 6639.2 6649.7 6727.6 6737.3 6738.8 4669.2 4694.5 4739.6 4751.2 4769.6 4126.8 4574.8 4645.1 4778.2 4874.3 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#4] prototype.html Cycles:180 Average:4592.94 Median:5142.22 stddev:1452.98 (28.3%) stddev-sans-first:1420.41 18:58:23 INFO - PROCESS | 1902 | Values: 272.5 308.2 309.3 315.0 316.0 4690.6 5106.0 5140.7 5504.8 5551.1 4900.6 4984.9 5292.5 5384.9 5520.1 3971.6 3977.7 4119.7 4165.2 4186.8 3460.7 4170.6 4174.0 4180.1 4206.4 3650.9 3707.6 4121.2 4163.4 4193.5 3230.9 3446.3 3955.9 4137.4 4200.7 3547.3 3550.5 3555.5 3567.6 3583.5 5296.9 5335.7 5368.5 5376.9 5431.2 3787.9 4637.9 4677.5 4730.8 4731.5 5658.5 5726.9 5766.7 5804.5 5821.5 3290.9 3909.6 3929.6 3935.5 3945.8 5830.4 6081.6 6084.6 6149.5 6161.6 3406.9 3647.4 3999.5 3999.9 4028.6 3365.6 3465.0 3711.6 3897.6 4071.8 3806.7 4175.9 4194.7 4724.4 4756.6 5119.6 5569.7 5654.0 5900.9 5919.2 1152.7 1180.8 1246.8 1311.4 1327.0 708.3 851.7 866.4 882.7 904.0 4629.8 4773.7 5072.5 5143.7 5409.7 5056.5 5496.7 5536.6 5611.3 6019.6 4899.5 5383.9 5514.3 5530.5 5535.4 2846.5 2880.8 2935.7 2962.0 2980.6 5512.4 5737.6 6004.5 6011.7 6014.3 4947.9 5083.3 5514.7 5737.3 5839.9 5482.6 5745.9 5948.3 5984.6 6006.6 4581.5 4951.9 5526.9 5757.5 5849.4 5064.8 5382.4 5457.9 5739.4 5746.7 4878.7 4933.2 5309.7 5370.7 5954.2 3293.5 3680.9 3814.3 3823.8 3855.4 6008.8 6010.3 6047.7 6054.4 6120.7 5810.3 5894.4 5914.0 5925.9 5941.6 5146.8 5235.2 5749.0 5812.4 5856.9 5103.4 5442.8 5734.3 5754.7 5764.9 4962.7 4982.4 5724.4 5879.6 5944.5 5736.6 5783.4 5815.4 5828.7 5838.0 18:58:23 INFO - PROCESS | 1902 | 18:58:23 INFO - PROCESS | 1902 | [#5] yui.html Cycles:180 Average:20104.93 Median:4268.54 stddev:29989.27 (702.6%) stddev-sans-first:30037.93 18:58:23 INFO - PROCESS | 1902 | Values: 680.9 691.7 726.7 745.7 761.3 13957.9 14483.7 14562.5 14569.8 14585.9 12791.2 12824.9 12829.7 12891.2 12963.2 643.8 669.0 788.4 816.5 822.2 808.2 818.7 819.6 819.7 823.4 867.9 868.0 869.8 871.9 872.5 841.3 860.3 863.6 866.0 866.2 613.9 632.8 638.7 643.3 645.4 13321.4 13613.5 13672.8 13728.5 14309.1 13102.3 13138.9 13184.2 13250.3 13375.9 10669.8 10923.1 10994.4 11041.6 11354.9 3478.1 3621.9 3682.6 3686.4 3696.0 86707.0 89054.2 89888.6 90966.5 91986.7 10287.2 10425.5 10460.6 10468.6 10510.0 20410.4 20486.9 20621.8 20711.7 20855.2 6679.6 7115.6 7297.9 7426.2 7447.4 3589.9 3748.8 4116.9 4227.2 4305.3 3334.4 3427.2 3462.7 3470.7 3483.6 617.5 628.1 628.4 630.4 643.6 564.0 565.7 605.9 650.1 657.7 8630.3 8929.5 9107.3 9228.5 9267.9 621.6 640.4 640.8 645.4 652.8 3846.3 4231.8 4379.6 4415.5 4480.8 100881.4 112999.4 113124.4 117147.8 118769.8 80761.0 82586.0 100743.5 105029.0 107323.9 78710.7 79656.6 90483.5 92408.5 94506.1 8273.7 8591.8 8718.6 8757.8 8958.6 46634.0 47310.0 47967.8 58818.0 59212.7 50507.0 56601.2 57661.6 67607.3 70587.6 9964.6 10076.9 11811.2 12031.0 12248.3 13340.3 13720.6 13726.9 13847.9 14007.5 12561.9 12562.4 12615.9 12640.4 12807.2 12786.2 12831.2 12860.9 12917.9 13042.5 9091.5 12120.3 12247.4 12312.6 12481.6 10542.2 10628.1 10668.4 10836.6 11145.1 9206.6 10625.7 10890.5 10950.8 10951.7 18:58:23 INFO - PROCESS | 1902 | -------- Summary: end -------- 18:58:23 INFO - PROCESS | 1902 | 18:58:24 INFO - TEST-INFO | 1902: exit 0 18:58:24 INFO - TEST-OK | dromaeo_css | took 1116018ms 18:58:24 INFO - TEST-START | kraken 18:58:24 INFO - operating with platform_type : mac_ 18:58:24 INFO - Initialising browser for kraken test... 18:58:24 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpGw3_6l/profile http://localhost:53230/getInfo.html) 18:58:27 INFO - PROCESS | 1907 | 2017-01-10 18:58:27.260 plugin-container[1908:20342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 18:58:27 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:27 INFO - PROCESS | 1907 | 2017-01-10 18:58:27.264 plugin-container[1908:20342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 18:58:27 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:27 INFO - PROCESS | 1907 | 2017-01-10 18:58:27.264 plugin-container[1908:20342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:58:27 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 18:58:27 INFO - PROCESS | 1907 | colorDepth:24 18:58:27 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 18:58:27 INFO - PROCESS | 1907 | __metrics 18:58:27 ERROR - PROCESS | 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 18:58:28 INFO - TEST-INFO | 1907: exit 0 18:58:28 INFO - Browser initialized. 18:58:28 INFO - Running cycle 1/1 for kraken test... 18:58:28 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpGw3_6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:30 INFO - PROCESS | 1909 | 2017-01-10 18:58:30.809 plugin-container[1910:20468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:58:30 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:30 INFO - PROCESS | 1909 | 2017-01-10 18:58:30.811 plugin-container[1910:20468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891f, name = 'com.apple.CFPasteboardClient' 18:58:30 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:30 INFO - PROCESS | 1909 | 2017-01-10 18:58:30.811 plugin-container[1910:20468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:58:40 INFO - PROCESS | 1909 | RSS: Main: 229154816 18:58:40 INFO - PROCESS | 1909 | 18:58:43 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53230/tests/kraken/driver.html?testName=audio-beat-detection) 18:58:44 INFO - PROCESS | 1909 | RSS: Main: 241184768 18:58:44 INFO - PROCESS | 1909 | 18:58:46 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53230/tests/kraken/driver.html?testName=audio-dft) 18:58:47 INFO - PROCESS | 1909 | RSS: Main: 249253888 18:58:47 INFO - PROCESS | 1909 | 18:58:50 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53230/tests/kraken/driver.html?testName=audio-fft) 18:58:51 INFO - PROCESS | 1909 | RSS: Main: 252788736 18:58:51 INFO - PROCESS | 1909 | 18:58:53 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53230/tests/kraken/driver.html?testName=audio-oscillator) 18:58:53 INFO - PROCESS | 1909 | RSS: Main: 254496768 18:58:53 INFO - PROCESS | 1909 | 18:58:55 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:58:56 INFO - PROCESS | 1909 | RSS: Main: 254255104 18:58:56 INFO - PROCESS | 1909 | 18:58:59 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53230/tests/kraken/driver.html?testName=imaging-darkroom) 18:59:00 INFO - PROCESS | 1909 | RSS: Main: 258265088 18:59:00 INFO - PROCESS | 1909 | 18:59:03 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53230/tests/kraken/driver.html?testName=imaging-desaturate) 18:59:03 INFO - PROCESS | 1909 | RSS: Main: 258641920 18:59:03 INFO - PROCESS | 1909 | 18:59:06 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53230/tests/kraken/driver.html?testName=json-parse-financial) 18:59:07 INFO - PROCESS | 1909 | RSS: Main: 257802240 18:59:07 INFO - PROCESS | 1909 | 18:59:09 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:59:09 INFO - PROCESS | 1909 | RSS: Main: 257028096 18:59:09 INFO - PROCESS | 1909 | 18:59:11 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:59:12 INFO - PROCESS | 1909 | RSS: Main: 257028096 18:59:12 INFO - PROCESS | 1909 | 18:59:14 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:59:15 INFO - PROCESS | 1909 | RSS: Main: 256966656 18:59:15 INFO - PROCESS | 1909 | 18:59:18 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:59:19 INFO - PROCESS | 1909 | RSS: Main: 257253376 18:59:19 INFO - PROCESS | 1909 | 18:59:22 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:59:22 INFO - PROCESS | 1909 | RSS: Main: 257388544 18:59:22 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:53230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53230/tests/kraken/driver.html?testName=ai-astar) 18:59:24 INFO - PROCESS | 1909 | RSS: Main: 258768896 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | __start_tp_report 18:59:24 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 18:59:24 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 18:59:24 INFO - PROCESS | 1909 | |i|pagename|runs| 18:59:24 INFO - PROCESS | 1909 | |0;ai-astar;107;118;118;115;117;116;119;117;118;118 18:59:24 INFO - PROCESS | 1909 | |1;audio-beat-detection;144;114;112;178;142;113;114;114;141;115 18:59:24 INFO - PROCESS | 1909 | |2;audio-dft;198;193;195;194;194;196;197;197;199;197 18:59:24 INFO - PROCESS | 1909 | |3;audio-fft;83;57;58;89;60;60;59;63;105;82 18:59:24 INFO - PROCESS | 1909 | |4;audio-oscillator;85;71;90;71;60;85;73;85;104;123 18:59:24 INFO - PROCESS | 1909 | |5;imaging-gaussian-blur;89;89;88;90;89;79;78;131;137;104 18:59:24 INFO - PROCESS | 1909 | |6;imaging-darkroom;107;100;118;102;98;114;99;103;138;115 18:59:24 INFO - PROCESS | 1909 | |7;imaging-desaturate;87;88;90;127;137;109;103;130;139;140 18:59:24 INFO - PROCESS | 1909 | |8;json-parse-financial;58;58;57;57;61;62;72;93;88;91 18:59:24 INFO - PROCESS | 1909 | |9;json-stringify-tinderbox;68;78;73;84;84;75;71;70;98;106 18:59:24 INFO - PROCESS | 1909 | |10;stanford-crypto-aes;84;87;85;86;88;85;84;86;87;82 18:59:24 INFO - PROCESS | 1909 | |11;stanford-crypto-ccm;144;138;138;125;149;156;166;151;207;164 18:59:24 INFO - PROCESS | 1909 | |12;stanford-crypto-pbkdf2;168;190;234;166;169;196;199;162;224;173 18:59:24 INFO - PROCESS | 1909 | |13;stanford-crypto-sha256-iterative;68;69;68;70;66;68;67;70;69;69 18:59:24 INFO - PROCESS | 1909 | __end_tp_report 18:59:24 INFO - PROCESS | 1909 | __start_cc_report 18:59:24 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,975 18:59:24 INFO - PROCESS | 1909 | __end_cc_report 18:59:24 INFO - PROCESS | 1909 | __startTimestamp1484103564743__endTimestamp 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | ------- Summary: start ------- 18:59:24 INFO - PROCESS | 1909 | Number of tests: 14 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#0] ai-astar Cycles:10 Average:116.30 Median:118.00 stddev:3.47 (2.9%) stddev-sans-first:1.22 18:59:24 INFO - PROCESS | 1909 | Values: 107.0 118.0 118.0 115.0 117.0 116.0 119.0 117.0 118.0 118.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:128.00 stddev:22.00 (17.2%) stddev-sans-first:22.62 18:59:24 INFO - PROCESS | 1909 | Values: 144.0 114.0 112.0 178.0 142.0 113.0 114.0 114.0 141.0 115.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#2] audio-dft Cycles:10 Average:196.00 Median:197.00 stddev:1.94 (1.0%) stddev-sans-first:1.92 18:59:24 INFO - PROCESS | 1909 | Values: 198.0 193.0 195.0 194.0 194.0 196.0 197.0 197.0 199.0 197.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#3] audio-fft Cycles:10 Average:71.60 Median:61.50 stddev:16.85 (27.4%) stddev-sans-first:17.36 18:59:24 INFO - PROCESS | 1909 | Values: 83.0 57.0 58.0 89.0 60.0 60.0 59.0 63.0 105.0 82.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#4] audio-oscillator Cycles:10 Average:84.70 Median:79.00 stddev:18.23 (23.1%) stddev-sans-first:19.33 18:59:24 INFO - PROCESS | 1909 | Values: 85.0 71.0 90.0 71.0 60.0 85.0 73.0 85.0 104.0 123.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:88.50 stddev:20.58 (23.2%) stddev-sans-first:21.60 18:59:24 INFO - PROCESS | 1909 | Values: 89.0 89.0 88.0 90.0 89.0 79.0 78.0 131.0 137.0 104.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:116.50 stddev:12.35 (10.6%) stddev-sans-first:13.07 18:59:24 INFO - PROCESS | 1909 | Values: 107.0 100.0 118.0 102.0 98.0 114.0 99.0 103.0 138.0 115.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#7] imaging-desaturate Cycles:10 Average:115.00 Median:139.50 stddev:22.04 (15.8%) stddev-sans-first:20.92 18:59:24 INFO - PROCESS | 1909 | Values: 87.0 88.0 90.0 127.0 137.0 109.0 103.0 130.0 139.0 140.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#8] json-parse-financial Cycles:10 Average:69.70 Median:67.00 stddev:15.16 (22.6%) stddev-sans-first:15.48 18:59:24 INFO - PROCESS | 1909 | Values: 58.0 58.0 57.0 57.0 61.0 62.0 72.0 93.0 88.0 91.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#9] json-stringify-tinderbox Cycles:10 Average:80.70 Median:76.50 stddev:12.61 (16.5%) stddev-sans-first:12.50 18:59:24 INFO - PROCESS | 1909 | Values: 68.0 78.0 73.0 84.0 84.0 75.0 71.0 70.0 98.0 106.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.81 18:59:24 INFO - PROCESS | 1909 | Values: 84.0 87.0 85.0 86.0 88.0 85.0 84.0 86.0 87.0 82.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:153.50 stddev:22.47 (14.6%) stddev-sans-first:23.55 18:59:24 INFO - PROCESS | 1909 | Values: 144.0 138.0 138.0 125.0 149.0 156.0 166.0 151.0 207.0 164.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.10 Median:193.00 stddev:25.27 (13.1%) stddev-sans-first:25.73 18:59:24 INFO - PROCESS | 1909 | Values: 168.0 190.0 234.0 166.0 169.0 196.0 199.0 162.0 224.0 173.0 18:59:24 INFO - PROCESS | 1909 | 18:59:24 INFO - PROCESS | 1909 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.33 18:59:24 INFO - PROCESS | 1909 | Values: 68.0 69.0 68.0 70.0 66.0 68.0 67.0 70.0 69.0 69.0 18:59:24 INFO - PROCESS | 1909 | -------- Summary: end -------- 18:59:24 INFO - PROCESS | 1909 | 18:59:25 INFO - TEST-INFO | 1909: exit 0 18:59:25 INFO - TEST-OK | kraken | took 61459ms 18:59:25 INFO - SUITE-END | took 1177s 18:59:25 INFO - Completed test suite (00:19:37) 18:59:25 INFO - javascript benchmark 18:59:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7170.761215793717, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.2257242120522, 3459.913502162447, 3462.965370346296, 3479.2171761353698, 3492.70312023478, 5863.41365863414, 5903.645781253123, 5916.4083591640765, 5925.31858836234, 5938.0, 5854.736536855841, 5912.763489460416, 5939.643621382709, 5944.910826337608, 5955.612885162462, 4511.52628973958, 5322.441143679906, 5330.200469929522, 5367.409584945653, 5381.623286369956, 5295.629305948585, 5310.654807437504, 5405.108157154064, 5406.216098665691, 5422.864428389283, 5215.8174463893965, 5267.815626453094, 5284.233786101032, 5319.069162896516, 5365.758540865631, 5247.501487358695, 5258.447862974371, 5301.973490132525, 5336.332958380202, 5351.759170837322, 5207.609429292818, 5234.7120908350025, 5276.947230527722, 5306.495882891119, 5332.68003919766, 5674.602777805555, 5809.419058094199, 5867.76528938842, 5876.4123587641325, 5945.91081133779, 6140.539459540552, 6254.0, 6268.65522396268, 6275.717592708339, 6299.905501417483, 4759.643501602027, 4918.893249018942, 5663.801766938137, 5985.042393217108, 6223.253209614875, 5805.129230615357, 5893.14549390336, 5995.880082398371, 6009.609375390673, 6024.246969128815, 6300.086487459293, 6315.652639104802, 6338.683065846689, 6396.968015159894, 6399.9360006400275, 6234.625922444668, 6238.906416403757, 6248.9062664060075, 6251.374862513712, 6251.749930002749, 6318.778842740482, 6336.144620476177, 6344.58760180596, 6350.4602108820345, 6367.140436041168, 6154.292256390483, 6206.689665516707, 6226.875462490725, 6228.190335256388, 6259.374062593795, 6207.379262073847, 6219.129321894939, 6252.843678908063, 6281.2462504498835, 6295.087212354275, 5015.648904576645, 5033.219850923039, 5047.949520504748, 5051.217061355495, 5052.216906379517, 4448.065906159743, 4456.086502267028, 4482.484514280899, 4484.529124441952, 4539.546045395434, 5150.660828184699, 5986.610870293417, 5986.73059712314, 6092.086187071886, 6102.72537735803, 6201.720922558496, 6298.086777417248, 6299.574663754487, 6300.369963003663, 6341.968290158612, 5856.297244330707, 5950.166976623276, 5969.283685957713, 5984.581079324493, 6034.969825150846, 5522.5581953443625, 5558.6942718150085, 5564.360098588633, 5571.470710282517, 5603.971980140073, 6228.6574238416415, 6270.435660790551, 6387.808365749035, 6392.232932048184, 6422.6467544285515, 6333.145025421509, 6343.5876668016435, 6347.428731414195, 6356.0, 6379.712912918837, 6497.350264973465, 6535.2811190768025, 6535.967320163369, 6538.346165383614, 6544.051112588743, 6084.939150608438, 6086.1175129606845, 6152.600080994721, 6229.626222426669, 6278.591891527036, 6158.722857471335, 6184.103305020837, 6207.627542347293, 6217.222847144107, 6223.222097237845, 5966.194563734014, 5968.850778730392, 5995.970020149872, 6037.577369584263, 6040.969795150996, 6013.548983826318, 6034.185384972972, 6109.951287404173, 6129.8784197560135, 6157.415045570664, 5282.996230716151, 5763.971180144073, 6014.548908831767, 6106.45041946227, 6182.350853160354, 6239.282482514569, 6265.8120256392385, 6269.404406581368, 6270.843228919313, 6286.654234017173, 5624.015797235549, 5667.20659107717, 6140.263168419819, 6151.907721384094, 6156.846078847885], "value": 5767.063300960673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.2264118921403, 483.6010291509505, 512.3825789923143, 518.4323166133083, 525.3197109742882, 35134.472982905274, 41607.95980100499, 41713.165736685245, 41751.37372939409, 41894.37158442618, 33201.16997075074, 40648.39027414591, 42873.0, 42946.570534294646, 43027.13945721084, 8083.232092951161, 9391.6712915048, 9441.150296473315, 9514.57184426699, 9534.80930381392, 9461.0, 9497.477638729868, 9514.952425237898, 9547.80904381912, 9556.856647150298, 9491.19324857388, 9492.477913714778, 9562.760930976712, 9599.184069354078, 9643.90356096437, 9421.670241541548, 9482.383645063082, 9490.098440648128, 9573.760655983586, 9608.183304419133, 9363.953180234123, 9411.482368469731, 9495.38280011797, 9496.382735122195, 9510.09654082861, 39740.60259397427, 40092.39861402052, 40159.79920100381, 40271.59049433256, 40308.59691403078, 3175.9682403176134, 3262.6411094779555, 3434.9828250858836, 3480.0, 3728.440733889911, 29735.40529189404, 30273.69726302731, 30445.69554304473, 30540.694593054228, 30875.0, 3073.89241376553, 3204.006757905057, 3215.5337476066074, 3245.578074850278, 3277.4919887417486, 518047.0, 518523.0, 520880.0, 523479.0, 524435.9999999962, 62727.0, 63133.68433157805, 63383.6830815843, 63473.0, 63561.68219158875, 65119.0, 65424.672876636265, 66355.33644663588, 66380.0, 66585.0, 16375.344986200656, 16411.42560010394, 16628.33486660544, 16638.25127869249, 16646.334146634243, 12168.0, 12559.183653062522, 12675.556355527697, 12677.809832852514, 12691.873081269254, 17970.561695273682, 18785.966771827407, 19283.93938333405, 19387.612247754965, 19403.80596194048, 2684.5704687249945, 3246.788958717676, 7403.81587431709, 7518.436117291225, 7602.517509085786, 866.5927014303268, 873.5108339329995, 911.1207684584408, 931.5156118818177, 939.6379367654367, 30256.84871575628, 30465.238369040508, 30477.390452190833, 30519.847400762854, 30826.53760193599, 1012.964546240878, 1054.915606751458, 1056.5245639462273, 1057.5082586597268, 1102.2890235798027, 14617.488387906586, 15357.616059598377, 15765.211739412984, 15818.446354377542, 16019.0, 616790.0, 619219.0, 622245.0, 624059.0, 626146.0, 47714.0, 56363.71818140965, 57039.429605704245, 57678.711606442535, 57703.71148144316, 1299.2918859221747, 1348.8853447457077, 1354.695193581456, 1390.65928847431, 1398.8041674165624, 28738.706758195513, 29633.0, 29636.110916672533, 30237.0, 30246.84876575603, 38258.0, 43059.9235019127, 51181.23228151506, 52800.0, 52879.0, 623676.0, 625286.0, 630436.0, 631546.0, 631580.0, 21936.780632193473, 26393.340166495993, 26415.60376594391, 26424.8678756605, 26471.470570588866, 41220.79389603033, 41794.58205418028, 44986.77506612446, 45171.548284516735, 45586.54413455823, 37653.81173094117, 37720.62279377171, 38105.42841857319, 38126.80936595299, 38698.0, 37774.62225377821, 37986.43020354641, 37990.62009379871, 38622.6137738619, 38775.0, 37021.62978370182, 37507.43738843865, 37581.4362784553, 37674.43488347622, 37927.05182370352, 32073.198170045936, 34387.312253755284, 34456.96629101131, 34501.0, 34675.306493870485, 25329.493410131327, 25456.745432546177, 25554.872225638752, 25970.87014564915, 26216.674248748743], "value": 19707.133969588416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.995395069074, 368.9981550092249, 376.4071587250081, 382.64414094891737, 401.9849879055385, 18493.0, 19344.129514171862, 19708.310209142684, 20048.89975550122, 20244.0, 19071.523211919673, 19238.32665856695, 19511.0, 19858.304959326426, 20003.799962000376, 5647.040003199466, 5686.232358631583, 5939.168516407706, 5944.405559444064, 5955.464008239258, 5685.687287199203, 5812.738426770784, 5840.182374467578, 5850.648961062328, 5870.442307980757, 5573.860653483675, 5742.023855944479, 5771.82684519463, 5814.98237808384, 5829.067349224125, 5711.400302968212, 5786.1610066540325, 5790.826275211751, 5833.649981001154, 5847.006008978484, 5020.246962955586, 5037.19404895215, 5094.79620815167, 5101.285819985205, 5110.361204849394, 24595.63106553403, 24748.0, 24760.25719228426, 24886.37784055395, 24996.250112496655, 1934.264979307868, 2203.7575866654656, 2211.9115235390564, 2257.266388423769, 2979.6424429068434, 9986.600535978552, 10224.897751022543, 14224.431022759078, 14476.0, 14924.850751492564, 1727.1105380728934, 1843.3732530939544, 1861.1066687989705, 1861.3950466098572, 1889.6409682160345, 123839.0, 125235.74764252332, 126678.09982850264, 128085.0, 130894.0, 11231.326120432801, 11602.593909213138, 11869.940650296694, 11894.286342819458, 11927.343996080126, 14433.566992990227, 14497.565073047614, 14521.346539405753, 14531.636709082357, 14533.491327803476, 7894.250046245597, 8205.876911846328, 8391.790205244795, 8428.157184281645, 8559.828803423896, 6913.702812042332, 8351.331893448509, 8379.0, 8496.320294376435, 8514.957425212959, 4321.546237645065, 4506.301523263899, 4530.343100250511, 4540.818367265272, 4543.750093744876, 1866.1975350599373, 1914.731937528747, 1916.6166766646727, 1918.4340619516997, 1919.4529559075875, 692.2800287700835, 707.4093132234642, 744.3449764207463, 794.8450052239873, 918.0727465260002, 19396.0, 19915.250002498597, 20317.49206269855, 20596.58806823855, 20808.27171049006, 793.5357815677893, 846.0017179727857, 944.4474982135528, 970.5001924009141, 1015.5988384588046, 9290.860637090449, 10092.899071009197, 10873.891261087289, 11057.72355691098, 11070.778584428428, 587970.0601496965, 588802.0559897174, 595964.0, 599545.0, 601229.0, 108092.45953770338, 108751.0, 118912.0, 130644.0, 141554.0, 918.6325469812128, 919.8252332056887, 931.4411353188033, 955.4267439536222, 989.1888651306004, 18165.909170454594, 18222.72665909986, 18389.793224813107, 18640.627187456445, 19724.704129438356, 55204.0, 55488.0, 56195.59511012257, 56775.71612141913, 56856.43143568511, 69548.26129346807, 78951.0, 80272.59863700643, 80598.19401806142, 82083.5895820517, 6015.39846015395, 8041.597920104088, 8043.19568043191, 8231.888695026095, 8403.537805420761, 12192.146549741434, 12653.810192847299, 12682.936585317015, 12991.870081299445, 13011.934940325238, 10941.234113612289, 11091.057260132979, 11236.887631123911, 11257.324560526067, 11295.265807722471, 10836.9163083691, 10974.945125274322, 10991.670249892515, 11056.447177640925, 11110.222284440011, 9758.853617195606, 11143.275687080313, 11341.773164536828, 11344.092472602008, 11432.371219583003, 7485.139208991036, 8058.355331573577, 8110.148434414302, 8304.70933517324, 8385.622646981023, 10196.388216706724, 10242.283040187413, 10855.837162442413, 10992.340459572451, 11218.943905280748], "value": 9448.599850882412, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.1944994103653, 1442.1131004432277, 1577.4005877766447, 1752.2552915011117, 1775.8490528305094, 7502.32479076883, 7732.420068494871, 7993.1607181246, 8443.240108390244, 8457.746267611974, 7327.890081648779, 7957.960210198956, 8116.797080073001, 8275.006999160109, 8359.164083591639, 2046.1201683276186, 2269.716285464317, 2306.538692261546, 2339.146211632759, 2346.8122550195944, 2313.178821518362, 2338.169949667869, 2344.800691941187, 2352.9411764705933, 2356.6229403295474, 2255.7180352455944, 2308.2382813671447, 2328.2549584133085, 2341.7072865891764, 2348.6711860339565, 2297.494551198742, 2309.0186670665066, 2327.2087490253225, 2329.580675478413, 2339.4853132310864, 2329.2779238436137, 2347.1550241912896, 2351.2123438648073, 2351.5061837014246, 2355.787979081891, 8155.306798922039, 8315.168483151698, 8330.83338333236, 8337.583120843934, 8340.791480212982, 5025.497450254982, 5175.327207463043, 5191.818286359997, 5193.9740301298625, 5208.6093542984045, 3293.670632936711, 3415.077928959192, 3501.28223714138, 3563.893083207508, 3582.9283414331567, 1021.6526381030485, 1050.4170185547018, 1089.1069323155016, 1099.1536516882036, 1099.571167244775, 10858.23992320538, 10861.022507974241, 10941.23411361205, 11000.449977501174, 11012.229143959847, 4366.43236379275, 4466.57567531084, 4486.327050942385, 4609.695152423788, 4677.64917631179, 7428.368588670023, 7887.645055972495, 8872.378933474723, 8891.422057566368, 9140.451572905647, 5148.922766158511, 5169.405518365361, 5178.300929374486, 5182.067227898939, 5187.170052791611, 4208.747475151501, 4494.797734101973, 4756.429228492603, 4757.381540399726, 4771.73755443447, 3181.0, 3310.1724568857785, 3763.8682646107254, 3841.961580384216, 3977.900552486211, 1253.4923356040817, 1271.199144538953, 1275.9553415630407, 1281.7564662714055, 1294.3204817470714, 720.2365492577779, 870.2602787630464, 882.069416765306, 886.7206829848577, 889.3330002498126, 5504.9449505504435, 5514.200440936122, 5514.7187423482965, 5518.061929471999, 5531.0, 442.76975972494125, 443.30622575668974, 443.3748414735208, 955.0974329258784, 991.8313886639289, 3134.5925029745986, 3567.4470457079196, 3646.690031347312, 3651.6348365163803, 3749.8500059997295, 9781.510924453749, 10193.949030254802, 10199.439030853226, 10214.693559193236, 10220.846687299696, 10000.699979000641, 10083.14293285079, 10113.595456181818, 10184.439855807854, 10229.0, 6730.32696730323, 7696.6536505857375, 7852.803679907934, 7856.921430785734, 7870.645820937957, 5201.193814958688, 5250.711210883438, 5318.202269659582, 5413.648112872651, 5498.367687715804, 9112.772180695536, 9156.450612963243, 9240.260779137787, 9419.481928493999, 9532.904670953201, 9433.952830235805, 9462.574184161867, 9491.57287922059, 9501.524923753923, 9604.9039509604, 6415.1981002374705, 7087.893681594677, 7128.180259270043, 7265.9273407265255, 7267.127944646676, 7923.643436045277, 7987.760367188994, 7988.600569971362, 8005.199480052182, 8016.7194148204535, 7040.5071644984355, 7192.532485388642, 7195.676194571152, 7216.89174662391, 7236.819079523054, 6959.304069593, 6991.161060672548, 7045.753398631024, 7055.717771289091, 7138.714451421885, 6639.236487803964, 6649.667516624053, 6727.562708423937, 6737.326267373223, 6738.764143254963, 4669.229577119873, 4694.507076756891, 4739.597134243628, 4751.192297309397, 4769.642276829182, 4126.7523948563185, 4574.81700731967, 4645.117427688729, 4778.1638213311835, 4874.341963834979], "value": 4329.183227844426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.45916855042736, 308.19868342309996, 309.31949710636593, 314.9763767717419, 316.0455424617656, 4690.57784799368, 5106.0, 5140.7172605506685, 5504.807211817147, 5551.1126217086185, 4900.58345040672, 4984.87537811554, 5292.497212764791, 5384.865378365543, 5520.1443776214755, 3971.62269584389, 3977.6618987386037, 4119.691023173259, 4165.229432554987, 4186.832526698929, 3460.723142148622, 4170.603792639709, 4173.9582604173875, 4180.1430706705205, 4206.3920369371035, 3650.945235821465, 3707.6292370762976, 4121.216968775923, 4163.437935878663, 4193.517745459266, 3230.8869189578486, 3446.3451944130784, 3955.9318983874336, 4137.379393091061, 4200.726952748077, 3547.2728090741334, 3550.467429885512, 3555.4889172101753, 3567.571891373052, 3583.5341405617105, 5296.861174847426, 5335.733213339357, 5368.543673787733, 5376.946230537684, 5431.158170483582, 3787.867424640124, 4637.86086417408, 4677.549959512562, 4730.787114579852, 4731.5268473152755, 5658.547316214693, 5726.914096288559, 5766.682832444214, 5804.548862784304, 5821.5342772578515, 3290.8683652653867, 3909.63162892988, 3929.5677475477673, 3935.4687117239378, 3945.842166313344, 5830.3586605473365, 6081.5742897996715, 6084.634921904656, 6149.538784591174, 6161.6303021818385, 3406.9488957665653, 3647.4133751818044, 3999.460072890123, 3999.8800035998966, 4028.576999415078, 3365.6129545102135, 3465.0297916583395, 3711.6473934976134, 3897.649211570972, 4071.796410179479, 3806.6764325032127, 4175.874723758292, 4194.6853985951175, 4724.433068031858, 4756.595689366373, 5119.590432765444, 5569.663280812576, 5654.0, 5900.911486327708, 5919.230500034968, 1152.6772503698978, 1180.7697498987786, 1246.82798169722, 1311.4229738914846, 1326.9601911942657, 708.2917082917083, 851.6891334662804, 866.4021824940771, 882.6734108379941, 903.9514163570358, 4629.791659375336, 4773.71357718538, 5072.492750724898, 5143.71709555978, 5409.702466364309, 5056.457780377021, 5496.725163741797, 5536.612437129363, 5611.3266408031295, 6019.568845757964, 4899.510048995072, 5383.865403364869, 5514.310711161105, 5530.474604912527, 5535.363433205152, 2846.4524250795835, 2880.7839412043763, 2935.708288353118, 2961.956250187407, 2980.5678176664264, 5512.39363669996, 5737.598368114275, 6004.528890421874, 6011.6693581853415, 6014.338422773491, 4947.876303092451, 5083.339165908482, 5514.696691681996, 5737.340205876294, 5839.89763685073, 5482.5613950883835, 5745.913811292834, 5948.286205655262, 5984.611000284967, 6006.609570377911, 4581.473130590025, 4951.851444456672, 5526.861828454321, 5757.481826635623, 5849.356570777126, 5064.797408103635, 5382.36264230532, 5457.945420545744, 5739.426057394227, 5746.741396637245, 4878.682885612424, 4933.199382225534, 5309.65487243328, 5370.650907690988, 5954.225950626565, 3293.5059741039036, 3680.9447858282683, 3814.332491813977, 3823.770573765583, 3855.36386496225, 6008.849778755566, 6010.3088144863905, 6047.697615119138, 6054.39456054391, 6120.663363515049, 5810.331811841692, 5894.351621321565, 5913.965056115248, 5925.874083924041, 5941.643501389931, 5146.819861304836, 5235.214717792362, 5749.0, 5812.360640329476, 5856.9121463178935, 5103.362079740033, 5442.775375540472, 5734.283214598176, 5754.712264386848, 5764.855878603068, 4962.680046930748, 4982.352294201731, 5724.398938111605, 5879.617824841372, 5944.524438044859, 5736.569757268138, 5783.363829978614, 5815.3603103657715, 5828.717682109929, 5837.970810145922], "value": 4120.560904480219, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.874038302914, 691.7475121580622, 726.6729971512821, 745.6569977810207, 761.3034073822453, 13957.860421395782, 14483.71032579349, 14562.490312839052, 14569.834413246954, 14585.854141458582, 12791.232526048443, 12824.871751282484, 12829.679258018554, 12891.162074465154, 12963.157394769358, 643.7972038807773, 668.9665516724168, 788.4126325887222, 816.5264146794844, 822.1737154160061, 808.2079562029215, 818.6561644109488, 819.6188772220916, 819.6721311475397, 823.3742355809568, 867.8828358171661, 868.0148031983713, 869.7564681889081, 871.860502319629, 872.5375550957971, 841.3269384492412, 860.3117505995178, 863.5854789700916, 866.0170706248422, 866.1598249697784, 613.8864310102646, 632.7627139822566, 638.7157714816908, 643.3212960326854, 645.370763505583, 13321.40053697586, 13613.455461781517, 13672.794908076386, 13728.519501817489, 14309.069910455892, 13102.344882755824, 13138.934305328508, 13184.208947463183, 13250.271235082068, 13375.866241337559, 10669.786604267949, 10923.071538919201, 10994.395308258043, 11041.613543525938, 11354.886451135466, 3478.095695119262, 3621.9275614487824, 3682.558093028827, 3686.4101743721144, 3695.9630403695887, 86707.0, 89054.21891562133, 89888.55055724745, 90966.54516727374, 91986.70033249156, 10287.177025837915, 10425.478726063819, 10460.581576736997, 10468.581256749645, 10510.0, 20410.387688369374, 20486.873221972637, 20621.793782061988, 20711.689324660143, 20855.165793368094, 6679.599224046573, 7115.644217789089, 7297.927020729725, 7426.220246874109, 7447.44144189188, 3589.94615080774, 3748.8200656268064, 4116.876493705194, 4227.154569086195, 4305.268104422255, 3334.383139119222, 3427.1946092668204, 3462.688358047788, 3470.687638112582, 3483.5645544306963, 617.5245061302771, 628.0673200297555, 628.3904612525843, 630.3979699387074, 643.6170478565247, 563.9802606908738, 565.6634302589953, 605.9363766804511, 650.1125963060389, 657.7040331850686, 8630.266427353745, 8929.508877011827, 9107.316951228684, 9228.492432916253, 9267.907320926839, 621.5586933277333, 640.4364159539575, 640.8077576726963, 645.3836586060302, 652.7878439507084, 3846.3268927937497, 4231.809568569422, 4379.627731642846, 4415.536368681306, 4480.820767169342, 100881.43985146396, 112999.43500282611, 113124.43437782758, 117147.82852171539, 118769.81230187761, 80761.0, 82586.0, 100743.49628251811, 105029.0, 107323.8535229291, 78710.73537278503, 79656.6017169922, 90483.54758226298, 92408.53795731113, 94506.05493944972, 8273.669053237803, 8591.785205369915, 8718.60766265507, 8757.824843503222, 8958.552072396484, 46634.0, 47310.0, 47967.760161198974, 58818.0, 59212.703936480044, 50507.0, 56601.15098273447, 57661.55846103713, 67607.3239267601, 70587.64706176436, 9964.551595178089, 10076.949615251877, 11811.173217874666, 12031.0, 12248.326342051272, 13340.332983350987, 13720.588382348546, 13726.86273137256, 13847.861521384657, 14007.509737159151, 12561.937190314355, 12562.37188140571, 12615.873841261837, 12640.431180596712, 12807.167534110626, 12786.232826030468, 12831.230126192459, 12860.935695321463, 12917.870821291666, 13042.54351097707, 9091.454512729257, 12120.333381664093, 12247.387630618256, 12312.630621081382, 12481.625551233477, 10542.15662746963, 10628.149748020294, 10668.359898406121, 10836.566537338418, 11145.108391328837, 9206.597646261358, 10625.734356641145, 10890.455477226265, 10950.835737464104, 10951.726206844893], "value": 7096.378885929127, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 115.0, 117.0, 116.0, 119.0, 117.0, 118.0, 118.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 112.0, 178.0, 142.0, 113.0, 114.0, 114.0, 141.0, 115.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 193.0, 195.0, 194.0, 194.0, 196.0, 197.0, 197.0, 199.0, 197.0], "value": 196.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 58.0, 89.0, 60.0, 60.0, 59.0, 63.0, 105.0, 82.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 90.0, 71.0, 60.0, 85.0, 73.0, 85.0, 104.0, 123.0], "value": 84.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 90.0, 89.0, 79.0, 78.0, 131.0, 137.0, 104.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 100.0, 118.0, 102.0, 98.0, 114.0, 99.0, 103.0, 138.0, 115.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 90.0, 127.0, 137.0, 109.0, 103.0, 130.0, 139.0, 140.0], "value": 115.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 57.0, 61.0, 62.0, 72.0, 93.0, 88.0, 91.0], "value": 69.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 73.0, 84.0, 84.0, 75.0, 71.0, 70.0, 98.0, 106.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 86.0, 88.0, 85.0, 84.0, 86.0, 87.0, 82.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 138.0, 125.0, 149.0, 156.0, 166.0, 151.0, 207.0, 164.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 190.0, 234.0, 166.0, 169.0, 196.0, 199.0, 162.0, 224.0, 173.0], "value": 188.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 70.0, 66.0, 68.0, 67.0, 70.0, 69.0, 69.0], "value": 68.4, "unit": "score"}]}]} 18:59:26 INFO - Return code: 0 18:59:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:59:26 ERROR - # TBPL SUCCESS # 18:59:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:59:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:59:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:59:26 INFO - 2017-01-10 18:59:26.109 screenresolution[1914:20854] starting screenresolution argv=screenresolution get 18:59:26 INFO - 2017-01-10 18:59:26.113 screenresolution[1914:20854] Display 0: 1600x1200x32@60 18:59:26 INFO - 2017-01-10 18:59:26.119 screenresolution[1915:20857] starting screenresolution argv=screenresolution list 18:59:26 INFO - Available Modes on Display 0 18:59:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:59:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:59:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:59:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:59:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:59:26 INFO - Intel Iris: 18:59:26 INFO - Chipset Model: Intel Iris 18:59:26 INFO - Type: GPU 18:59:26 INFO - Bus: Built-In 18:59:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:59:26 INFO - Vendor: Intel (0x8086) 18:59:26 INFO - Device ID: 0x0a2e 18:59:26 INFO - Revision ID: 0x0009 18:59:26 INFO - Displays: 18:59:26 INFO - Display: 18:59:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:59:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:59:26 INFO - Display Serial Number: 1600x1200 60 18:59:26 INFO - Main Display: Yes 18:59:26 INFO - Mirror: Off 18:59:26 INFO - Online: Yes 18:59:26 INFO - Rotation: Supported 18:59:26 INFO - Return code: 0 18:59:26 INFO - Running post-action listener: _resource_record_post_action 18:59:26 INFO - [mozharness: 2017-01-11 02:59:26.362164Z] Finished run-tests step (success) 18:59:26 INFO - Running post-run listener: _resource_record_post_run 18:59:26 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 2813952; Write bytes: 656804864; Read time: 15; Write time: 1884 18:59:26 INFO - TinderboxPrint: CPU usage
54.4% 18:59:26 INFO - TinderboxPrint: I/O read bytes / time
2,813,952 / 15 18:59:26 INFO - TinderboxPrint: I/O write bytes / time
656,804,864 / 1,884 18:59:26 INFO - TinderboxPrint: CPU idle
2,207.8 (45.6%) 18:59:26 INFO - TinderboxPrint: CPU system
979.9 (20.3%) 18:59:26 INFO - TinderboxPrint: CPU user
1,650.0 (34.1%) 18:59:26 INFO - TinderboxPrint: Swap in / out
351,326,208 / 0 18:59:26 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344979968; Write bytes: 346908672; Read time: 26913; Write time: 699 18:59:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 2813952; Write bytes: 302949376; Read time: 15; Write time: 1155 18:59:26 INFO - Running post-run listener: _upload_blobber_files 18:59:26 INFO - Blob upload gear active. 18:59:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:59:26 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 18:59:26 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'] 18:59:26 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 18:59:27 INFO - (blobuploader) - INFO - Open directory for files ... 18:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:59:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:27 INFO - (blobuploader) - INFO - Done attempting. 18:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:28 INFO - (blobuploader) - INFO - Done attempting. 18:59:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:59:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:59:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:31 INFO - (blobuploader) - INFO - Done attempting. 18:59:31 INFO - (blobuploader) - INFO - Iteration through files over. 18:59:31 INFO - Return code: 0 18:59:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:59:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:59:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddd12c65455f2a1fad2e9c892de87d9452cba4aa89992bbc967994c69823031b84a181355cec4aa21581b1301961742dbbf286792bc235d94c6d12dda4192437", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f5598cd4eba3b401038a8fe1d93e033b2623719a26f9008eef565d16d714081b6ff31b7574b437e647bf5a52fb564c1baa95689567792d036809680dc20dd4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f26c6803cabc3fc71971cd0371128e6a09efe29e453118507cf6495d4a7fc1be0f744c99c45843fb0554bc9cc6897a7281af6f763c15778238e5e6e5a57ef70"} 18:59:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:59:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:59:31 INFO - Contents: 18:59:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddd12c65455f2a1fad2e9c892de87d9452cba4aa89992bbc967994c69823031b84a181355cec4aa21581b1301961742dbbf286792bc235d94c6d12dda4192437", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f5598cd4eba3b401038a8fe1d93e033b2623719a26f9008eef565d16d714081b6ff31b7574b437e647bf5a52fb564c1baa95689567792d036809680dc20dd4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f26c6803cabc3fc71971cd0371128e6a09efe29e453118507cf6495d4a7fc1be0f744c99c45843fb0554bc9cc6897a7281af6f763c15778238e5e6e5a57ef70"} 18:59:31 INFO - Running post-run listener: copy_logs_to_upload_dir 18:59:31 INFO - Copying logs to upload dir... 18:59:31 INFO - mkdir: /builds/slave/test/build/upload/logs 18:59:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.323332 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-10 18:59:31.671086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 18:59:31.672373) ========= 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.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddd12c65455f2a1fad2e9c892de87d9452cba4aa89992bbc967994c69823031b84a181355cec4aa21581b1301961742dbbf286792bc235d94c6d12dda4192437", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f5598cd4eba3b401038a8fe1d93e033b2623719a26f9008eef565d16d714081b6ff31b7574b437e647bf5a52fb564c1baa95689567792d036809680dc20dd4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f26c6803cabc3fc71971cd0371128e6a09efe29e453118507cf6495d4a7fc1be0f744c99c45843fb0554bc9cc6897a7281af6f763c15778238e5e6e5a57ef70"} build_url:https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/TjpdX9JkRW6PoHsito6_Hg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddd12c65455f2a1fad2e9c892de87d9452cba4aa89992bbc967994c69823031b84a181355cec4aa21581b1301961742dbbf286792bc235d94c6d12dda4192437", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f5598cd4eba3b401038a8fe1d93e033b2623719a26f9008eef565d16d714081b6ff31b7574b437e647bf5a52fb564c1baa95689567792d036809680dc20dd4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f26c6803cabc3fc71971cd0371128e6a09efe29e453118507cf6495d4a7fc1be0f744c99c45843fb0554bc9cc6897a7281af6f763c15778238e5e6e5a57ef70"}' ========= master_lag: 0.23 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 18:59:31.918482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-01-10 18:59:31.918847) ========= 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.dsA9OfNsJc/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.FFGYteJP0X/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005179 ========= master_lag: 3.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-01-10 18:59:34.951475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 18:59:34.951817) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 18:59:34.962464) ========= ========= Total master_lag: 3.60 =========