builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0085 starttime: 1486090860.73 results: success (0) buildid: 20170202162508 builduid: 252d3287eb1c4ac09fc313de64aaa153 revision: b9e7643b8be64976276573389670f70d57e1fe9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.733915) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.734346) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.734638) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.758860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.759179) ========= 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.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-02-02 19:01:00-- 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% 7.96M=0.001s 2017-02-02 19:01:00 (7.96 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.885712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.886028) ========= 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.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.019860 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:00.933860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-02 19:01:00.934173) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b9e7643b8be64976276573389670f70d57e1fe9f --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-beta --rev b9e7643b8be64976276573389670f70d57e1fe9f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-02-02 19:01:01,019 truncating revision to first 12 chars 2017-02-02 19:01:01,019 Setting DEBUG logging. 2017-02-02 19:01:01,019 attempt 1/10 2017-02-02 19:01:01,019 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b9e7643b8be6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 19:01:01,815 unpacking tar archive at: mozilla-beta-b9e7643b8be6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.077307 ========= master_lag: 1.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-02 19:01:03.636861) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:03.637192) ========= script_repo_revision: b9e7643b8be64976276573389670f70d57e1fe9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:03.641662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:03.641951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 19:01:03.669582) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-02 19:01:03.669929) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:03 INFO - MultiFileLogger online at 20170202 19:01:03 in /builds/slave/test 19:01:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:03 INFO - {'append_to_log': False, 19:01:03 INFO - 'base_work_dir': '/builds/slave/test', 19:01:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:01:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:03 INFO - 'branch': 'Mozilla-Beta', 19:01:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:03 INFO - 'config_files': ('talos/mac_config.py',), 19:01:03 INFO - 'default_actions': ('clobber', 19:01:03 INFO - 'read-buildbot-config', 19:01:03 INFO - 'download-and-extract', 19:01:03 INFO - 'populate-webroot', 19:01:03 INFO - 'create-virtualenv', 19:01:03 INFO - 'install', 19:01:03 INFO - 'run-tests'), 19:01:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:03 INFO - 'download_minidump_stackwalk': True, 19:01:03 INFO - 'download_symbols': 'ondemand', 19:01:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:03 INFO - 'installer_path': 'installer.exe', 19:01:03 INFO - 'log_level': 'info', 19:01:03 INFO - 'log_name': 'talos', 19:01:03 INFO - 'log_to_console': True, 19:01:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:01:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:01:03 INFO - 'opt_config_files': (), 19:01:03 INFO - 'pip_index': False, 19:01:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:03 INFO - 'cmd': ('bash', 19:01:03 INFO - '-c', 19:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:03 INFO - 'enabled': True, 19:01:03 INFO - 'halt_on_failure': False, 19:01:03 INFO - 'name': 'check_screen_resolution'},), 19:01:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:03 INFO - 'cmd': ('bash', 19:01:03 INFO - '-c', 19:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:03 INFO - 'enabled': True, 19:01:03 INFO - 'halt_on_failure': False, 19:01:03 INFO - 'name': 'check_screen_resolution'},), 19:01:03 INFO - 'run_cmd_checks_enabled': True, 19:01:03 INFO - 'sps_profile': False, 19:01:03 INFO - 'sps_profile_interval': 0, 19:01:03 INFO - 'suite': 'dromaeojs-e10s', 19:01:03 INFO - 'system_bits': '32', 19:01:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:03 INFO - 'title': 't-yosemite-r7-0085', 19:01:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:03 INFO - 'use_talos_json': True, 19:01:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:03 INFO - 'work_dir': 'build'} 19:01:03 INFO - [mozharness: 2017-02-03 03:01:03.881860Z] Running clobber step. 19:01:03 INFO - Running pre-action listener: _resource_record_pre_action 19:01:03 INFO - Running main action method: clobber 19:01:03 INFO - rmtree: /builds/slave/test/build 19:01:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:05 INFO - Running post-action listener: _resource_record_post_action 19:01:05 INFO - [mozharness: 2017-02-03 03:01:05.645563Z] Finished clobber step (success) 19:01:05 INFO - [mozharness: 2017-02-03 03:01:05.645711Z] Running read-buildbot-config step. 19:01:05 INFO - Running pre-action listener: _resource_record_pre_action 19:01:05 INFO - Running main action method: read_buildbot_config 19:01:05 INFO - Using buildbot properties: 19:01:05 INFO - { 19:01:05 INFO - "project": "", 19:01:05 INFO - "product": "firefox", 19:01:05 INFO - "script_repo_revision": "production", 19:01:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:01:05 INFO - "repository": "", 19:01:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:01:05 INFO - "buildid": "20170202162508", 19:01:05 INFO - "pgo_build": "False", 19:01:05 INFO - "basedir": "/builds/slave/test", 19:01:05 INFO - "buildnumber": 56, 19:01:05 INFO - "slavename": "t-yosemite-r7-0085", 19:01:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:01:05 INFO - "platform": "yosemite_r7", 19:01:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:01:05 INFO - "branch": "mozilla-beta", 19:01:05 INFO - "repo_path": "releases/mozilla-beta", 19:01:05 INFO - "revision": "b9e7643b8be64976276573389670f70d57e1fe9f", 19:01:05 INFO - "stage_platform": "macosx64", 19:01:05 INFO - "builduid": "252d3287eb1c4ac09fc313de64aaa153", 19:01:05 INFO - "slavebuilddir": "test" 19:01:05 INFO - } 19:01:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 19:01:05 INFO - Running post-action listener: _resource_record_post_action 19:01:05 INFO - [mozharness: 2017-02-03 03:01:05.649005Z] Finished read-buildbot-config step (success) 19:01:05 INFO - [mozharness: 2017-02-03 03:01:05.649136Z] Running download-and-extract step. 19:01:05 INFO - Running pre-action listener: _resource_record_pre_action 19:01:05 INFO - Running main action method: download_and_extract 19:01:05 INFO - mkdir: /builds/slave/test/build/tests 19:01: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')]} 19:01:05 INFO - trying https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 19:01:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 19:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 19:01:06 INFO - Downloaded 1165 bytes. 19:01:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 19:01:06 INFO - Using the following test package requirements: 19:01:06 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 19:01:06 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 19:01:06 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 19:01:06 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:01:06 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 19:01:06 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 19:01:06 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 19:01:06 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 19:01:06 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 19:01:06 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:01:06 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 19:01:06 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 19:01:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 19:01:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 19:01:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 19:01:07 INFO - Http code: 200 19:01:07 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 19:01:07 INFO - x-amz-cf-id: 41tRDYWlyGD6FwD6HirUZifYSERxHTxtBiEfxaYCRAPG5IV1MzmM4w== 19:01:07 INFO - x-amz-version-id: _Kld7P6Y.j0mCra0iUsFqBT8vNB6j2si 19:01:07 INFO - Expected file size: 16092090 19:01:07 INFO - Obtained file size: 16092090 19:01:14 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:01:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 19:01:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 19:01:14 INFO - Fetch https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 19:01:15 INFO - Http code: 200 19:01:15 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 19:01:15 INFO - x-amz-cf-id: Rd85VkVcJ2u7KLzNtCt01LEXOFRz6DBOmxfgmaRxm-FaghFT_U2TZw== 19:01:15 INFO - x-amz-version-id: _Kld7P6Y.j0mCra0iUsFqBT8vNB6j2si 19:01:15 INFO - Expected file size: 16092090 19:01:15 INFO - Obtained file size: 16092090 19:01:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 19:01:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 19:01:21 INFO - Fetch https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 19:01:22 INFO - Http code: 200 19:01:22 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 19:01:22 INFO - x-amz-cf-id: DjJ2qHidzZpi66mp7U6bFwKB5MPQ5d9NhZV1nS8UumqO68UNc0Xf5g== 19:01:22 INFO - x-amz-version-id: wZxrN2kBkdauEbGIYTUvRJSECbDpSos0 19:01:22 INFO - Expected file size: 14403005 19:01:22 INFO - Obtained file size: 14403005 19:01:23 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')]} 19:01:23 INFO - trying https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:01:23 INFO - Downloading https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 19:01:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 19:01:25 INFO - Downloaded 82132712 bytes. 19:01:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:01:25 INFO - mkdir: /builds/slave/test/properties 19:01:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:01:25 INFO - Writing to file /builds/slave/test/properties/build_url 19:01:25 INFO - Contents: 19:01:25 INFO - build_url:https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:01:26 INFO - Running post-action listener: _resource_record_post_action 19:01:26 INFO - Running post-action listener: set_extra_try_arguments 19:01:26 INFO - [mozharness: 2017-02-03 03:01:26.118297Z] Finished download-and-extract step (success) 19:01:26 INFO - [mozharness: 2017-02-03 03:01:26.118417Z] Running populate-webroot step. 19:01:26 INFO - Running pre-action listener: _resource_record_pre_action 19:01:26 INFO - Running main action method: populate_webroot 19:01:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:01:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:01:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:01:26 INFO - '--tppagecycles', 19:01:26 INFO - '7'], 19:01:26 INFO - 'tests': ['tsvgm']}}, 19:01:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:01:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:01:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:01:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:01:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['damp', 'tps']}, 19:01:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'tests': ['damp', 'tps']}, 19:01:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['dromaeo_dom']}, 19:01:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:01:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['basic_compositor_video']}, 19:01:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:01:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'other-e10s': {'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['a11yr', 19:01:26 INFO - 'ts_paint', 19:01:26 INFO - 'tpaint', 19:01:26 INFO - 'sessionrestore', 19:01:26 INFO - 'sessionrestore_no_auto_restore', 19:01:26 INFO - 'tabpaint']}, 19:01:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['tsvgx', 19:01:26 INFO - 'tsvgr_opacity', 19:01:26 INFO - 'tart', 19:01:26 INFO - 'tscrollx', 19:01:26 INFO - 'cart']}, 19:01:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:01:26 INFO - 'tsvgr_opacity', 19:01:26 INFO - 'tart', 19:01:26 INFO - 'tscrollx', 19:01:26 INFO - 'cart']}, 19:01:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'talos_options': ['--disable-e10s'], 19:01:26 INFO - 'tests': ['tp5o']}, 19:01:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'tests': ['tp5o']}, 19:01:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'talos_options': ['--disable-e10s', 19:01:26 INFO - '--xperf_path', 19:01:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:26 INFO - 'tests': ['tp5n']}, 19:01:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:01:26 INFO - 'talos_options': ['--xperf_path', 19:01:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:26 INFO - 'tests': ['tp5n']}}, 19:01:26 INFO - 'talos.zip': {'path': '', 19:01:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:01:26 INFO - Running post-action listener: _resource_record_post_action 19:01:26 INFO - [mozharness: 2017-02-03 03:01:26.137280Z] Finished populate-webroot step (success) 19:01:26 INFO - [mozharness: 2017-02-03 03:01:26.137392Z] Running create-virtualenv step. 19:01:26 INFO - Running pre-action listener: _resource_record_pre_action 19:01:26 INFO - Running main action method: create_virtualenv 19:01:26 INFO - Creating virtualenv /builds/slave/test/build/venv 19:01:26 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 19:01:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:01:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:01:26 INFO - Using real prefix '/tools/python27' 19:01:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:01:26 INFO - Installing distribute.............................................................................................................................................................................................done. 19:01:29 INFO - Installing pip.................done. 19:01:29 INFO - Return code: 0 19:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:01:30 INFO - Reading from file tmpfile_stdout 19:01:30 INFO - Output received: 19:01:30 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) 19:01:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:01:30 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')]} 19:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:30 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')]} 19:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:30 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:30 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 19:01:30 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 19:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:30 INFO - 'HOME': '/Users/cltbld', 19:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:30 INFO - 'LOGNAME': 'cltbld', 19:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:30 INFO - 'MOZ_NO_REMOTE': '1', 19:01:30 INFO - 'NO_EM_RESTART': '1', 19:01:30 INFO - 'PAGER': '/bin/cat', 19:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:30 INFO - 'PWD': '/builds/slave/test', 19:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:30 INFO - 'SHELL': '/bin/bash', 19:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:30 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:30 INFO - 'USER': 'cltbld', 19:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:30 INFO - 'XPC_FLAGS': '0x0', 19:01:30 INFO - 'XPC_SERVICE_NAME': '0', 19:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:30 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 19:01:30 INFO - Cleaning up... 19:01:30 INFO - Return code: 0 19:01:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:01:30 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')]} 19:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:30 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')]} 19:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:30 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:30 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 19:01:30 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 19:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:30 INFO - 'HOME': '/Users/cltbld', 19:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:30 INFO - 'LOGNAME': 'cltbld', 19:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:30 INFO - 'MOZ_NO_REMOTE': '1', 19:01:30 INFO - 'NO_EM_RESTART': '1', 19:01:30 INFO - 'PAGER': '/bin/cat', 19:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:30 INFO - 'PWD': '/builds/slave/test', 19:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:30 INFO - 'SHELL': '/bin/bash', 19:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:30 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:30 INFO - 'USER': 'cltbld', 19:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:30 INFO - 'XPC_FLAGS': '0x0', 19:01:30 INFO - 'XPC_SERVICE_NAME': '0', 19:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:30 INFO - Downloading/unpacking psutil>=3.1.1 19:01:30 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 19:01:30 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 19:01:30 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 19:01:30 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 19:01:30 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 19:01:30 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 19:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:34 INFO - Installing collected packages: psutil 19:01:34 INFO - Running setup.py install for psutil 19:01:34 INFO - building 'psutil._psutil_osx' extension 19:01:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:01:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:01:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:01:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:01:34 INFO - building 'psutil._psutil_posix' extension 19:01:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:01:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:01:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:01:34 INFO - ^ 19:01:34 INFO - 1 warning generated. 19:01:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:01:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:34 INFO - Successfully installed psutil 19:01:34 INFO - Cleaning up... 19:01:34 INFO - Return code: 0 19:01:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:34 INFO - 'HOME': '/Users/cltbld', 19:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:34 INFO - 'LOGNAME': 'cltbld', 19:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:34 INFO - 'MOZ_NO_REMOTE': '1', 19:01:34 INFO - 'NO_EM_RESTART': '1', 19:01:34 INFO - 'PAGER': '/bin/cat', 19:01:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:34 INFO - 'PWD': '/builds/slave/test', 19:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:34 INFO - 'SHELL': '/bin/bash', 19:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:34 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:34 INFO - 'USER': 'cltbld', 19:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:34 INFO - 'XPC_FLAGS': '0x0', 19:01:34 INFO - 'XPC_SERVICE_NAME': '0', 19:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:01:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:01:37 INFO - Installing collected packages: mozsystemmonitor 19:01:37 INFO - Running setup.py install for mozsystemmonitor 19:01:38 INFO - Successfully installed mozsystemmonitor 19:01:38 INFO - Cleaning up... 19:01:38 INFO - Return code: 0 19:01:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:38 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:38 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 19:01:38 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 19:01:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:38 INFO - 'HOME': '/Users/cltbld', 19:01:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:38 INFO - 'LOGNAME': 'cltbld', 19:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:38 INFO - 'MOZ_NO_REMOTE': '1', 19:01:38 INFO - 'NO_EM_RESTART': '1', 19:01:38 INFO - 'PAGER': '/bin/cat', 19:01:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:38 INFO - 'PWD': '/builds/slave/test', 19:01:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:38 INFO - 'SHELL': '/bin/bash', 19:01:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:38 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:38 INFO - 'USER': 'cltbld', 19:01:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:38 INFO - 'XPC_FLAGS': '0x0', 19:01:38 INFO - 'XPC_SERVICE_NAME': '0', 19:01:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:38 INFO - Downloading/unpacking jsonschema==2.5.1 19:01:38 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 19:01:38 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 19:01:38 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 19:01:38 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 19:01:38 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 19:01:38 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 19:01:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:01:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:41 INFO - Downloading functools32-3.2.3-2.tar.gz 19:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:01:41 INFO - warning: no files found matching '*.txt' 19:01:41 INFO - no previously-included directories found matching 'build' 19:01:41 INFO - no previously-included directories found matching 'dist' 19:01:41 INFO - no previously-included directories found matching '.git*' 19:01:41 INFO - Installing collected packages: jsonschema, functools32 19:01:41 INFO - Running setup.py install for functools32 19:01:41 INFO - warning: no files found matching '*.txt' 19:01:41 INFO - no previously-included directories found matching 'build' 19:01:41 INFO - no previously-included directories found matching 'dist' 19:01:41 INFO - no previously-included directories found matching '.git*' 19:01:41 INFO - Successfully installed jsonschema functools32 19:01:41 INFO - Cleaning up... 19:01:41 INFO - Return code: 0 19:01:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:41 INFO - 'HOME': '/Users/cltbld', 19:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:41 INFO - 'LOGNAME': 'cltbld', 19:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:41 INFO - 'MOZ_NO_REMOTE': '1', 19:01:41 INFO - 'NO_EM_RESTART': '1', 19:01:41 INFO - 'PAGER': '/bin/cat', 19:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:41 INFO - 'PWD': '/builds/slave/test', 19:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:41 INFO - 'SHELL': '/bin/bash', 19:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:41 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:41 INFO - 'USER': 'cltbld', 19:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:41 INFO - 'XPC_FLAGS': '0x0', 19:01:41 INFO - 'XPC_SERVICE_NAME': '0', 19:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:01:42 INFO - Cleaning up... 19:01:42 INFO - Return code: 0 19:01:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:42 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:42 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 19:01:42 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 19:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:42 INFO - 'HOME': '/Users/cltbld', 19:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:42 INFO - 'LOGNAME': 'cltbld', 19:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:42 INFO - 'MOZ_NO_REMOTE': '1', 19:01:42 INFO - 'NO_EM_RESTART': '1', 19:01:42 INFO - 'PAGER': '/bin/cat', 19:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:42 INFO - 'PWD': '/builds/slave/test', 19:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:42 INFO - 'SHELL': '/bin/bash', 19:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:42 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:42 INFO - 'USER': 'cltbld', 19:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:42 INFO - 'XPC_FLAGS': '0x0', 19:01:42 INFO - 'XPC_SERVICE_NAME': '0', 19:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:01:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:01:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:46 INFO - Downloading docopt-0.6.1.tar.gz 19:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:01:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:01:46 INFO - Running setup.py install for blobuploader 19:01:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:01:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:01:46 INFO - Running setup.py install for requests 19:01:46 INFO - Running setup.py install for docopt 19:01:46 INFO - Successfully installed blobuploader requests docopt 19:01:46 INFO - Cleaning up... 19:01:47 INFO - Return code: 0 19:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:47 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:47 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 19:01:47 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 19:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:47 INFO - 'HOME': '/Users/cltbld', 19:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:47 INFO - 'LOGNAME': 'cltbld', 19:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:47 INFO - 'MOZ_NO_REMOTE': '1', 19:01:47 INFO - 'NO_EM_RESTART': '1', 19:01:47 INFO - 'PAGER': '/bin/cat', 19:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:47 INFO - 'PWD': '/builds/slave/test', 19:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:47 INFO - 'SHELL': '/bin/bash', 19:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:47 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:47 INFO - 'USER': 'cltbld', 19:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:47 INFO - 'XPC_FLAGS': '0x0', 19:01:47 INFO - 'XPC_SERVICE_NAME': '0', 19:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Ll51eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-TfbyPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-AFnMfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-f01vHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-7dIzOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-CggJvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-lXkWVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-vnpxDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-HPc9Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-DeoQZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-tWPq48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Njmdtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-wPkqPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-eSWnQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-sqWJov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-wKdT6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:48 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-gszp0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:01:49 INFO - Running setup.py install for manifestparser 19:01:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:01:49 INFO - Running setup.py install for mozcrash 19:01:49 INFO - Running setup.py install for mozdebug 19:01:49 INFO - Running setup.py install for mozdevice 19:01:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:01:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:01:49 INFO - Running setup.py install for mozfile 19:01:49 INFO - Running setup.py install for mozhttpd 19:01:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:01:49 INFO - Running setup.py install for mozinfo 19:01:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Running setup.py install for mozInstall 19:01:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Running setup.py install for mozleak 19:01:50 INFO - Running setup.py install for mozlog 19:01:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Running setup.py install for moznetwork 19:01:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:01:50 INFO - Running setup.py install for mozprocess 19:01:50 INFO - Running setup.py install for mozprofile 19:01:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:01:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:01:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:01:51 INFO - Running setup.py install for mozrunner 19:01:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:01:51 INFO - Running setup.py install for mozscreenshot 19:01:51 INFO - Running setup.py install for moztest 19:01:51 INFO - Running setup.py install for mozversion 19:01:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:01:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:01:51 INFO - Cleaning up... 19:01:51 INFO - Return code: 0 19:01:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:51 INFO - 'HOME': '/Users/cltbld', 19:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:51 INFO - 'LOGNAME': 'cltbld', 19:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:51 INFO - 'MOZ_NO_REMOTE': '1', 19:01:51 INFO - 'NO_EM_RESTART': '1', 19:01:51 INFO - 'PAGER': '/bin/cat', 19:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:51 INFO - 'PWD': '/builds/slave/test', 19:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:51 INFO - 'SHELL': '/bin/bash', 19:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:51 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:51 INFO - 'USER': 'cltbld', 19:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:51 INFO - 'XPC_FLAGS': '0x0', 19:01:51 INFO - 'XPC_SERVICE_NAME': '0', 19:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zhC7os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:52 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)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-DeGHJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:52 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)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-L0028C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ThR0AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:52 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)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-KxOWnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-30czB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Vwd4z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-jloFjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-sXriLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:52 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-fKEone-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:53 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)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-OK8yeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:53 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)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-bLQbfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:53 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)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-9dUU5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-K0faQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-QPygR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-5pUg6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:53 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Odx9Mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:01:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01:56 INFO - Downloading blessings-1.6.tar.gz 19:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:01:56 INFO - Installing collected packages: blessings 19:01:56 INFO - Running setup.py install for blessings 19:01:56 INFO - Successfully installed blessings 19:01:56 INFO - Cleaning up... 19:01:56 INFO - Return code: 0 19:01:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:57 INFO - Reading from file tmpfile_stdout 19:01:57 INFO - Current package versions: 19:01:57 INFO - blessings == 1.6 19:01:57 INFO - blobuploader == 1.2.4 19:01:57 INFO - docopt == 0.6.1 19:01:57 INFO - functools32 == 3.2.3-2 19:01:57 INFO - jsonschema == 2.5.1 19:01:57 INFO - manifestparser == 1.1 19:01:57 INFO - mozInstall == 1.12 19:01:57 INFO - mozcrash == 1.0 19:01:57 INFO - mozdebug == 0.1 19:01:57 INFO - mozdevice == 0.48 19:01:57 INFO - mozfile == 1.2 19:01:57 INFO - mozhttpd == 0.7 19:01:57 INFO - mozinfo == 0.9 19:01:57 INFO - mozleak == 0.1 19:01:57 INFO - mozlog == 3.4 19:01:57 INFO - moznetwork == 0.27 19:01:57 INFO - mozprocess == 0.23 19:01:57 INFO - mozprofile == 0.28 19:01:57 INFO - mozrunner == 6.13 19:01:57 INFO - mozscreenshot == 0.1 19:01:57 INFO - mozsystemmonitor == 0.3 19:01:57 INFO - moztest == 0.8 19:01:57 INFO - mozversion == 1.4 19:01:57 INFO - psutil == 3.1.1 19:01:57 INFO - requests == 1.2.3 19:01:57 INFO - wsgiref == 0.1.2 19:01:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:58 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 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:58 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 19:01:58 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 19:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:58 INFO - 'HOME': '/Users/cltbld', 19:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:58 INFO - 'LOGNAME': 'cltbld', 19:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:58 INFO - 'MOZ_NO_REMOTE': '1', 19:01:58 INFO - 'NO_EM_RESTART': '1', 19:01:58 INFO - 'PAGER': '/bin/cat', 19:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:58 INFO - 'PWD': '/builds/slave/test', 19:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:58 INFO - 'SHELL': '/bin/bash', 19:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:58 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:58 INFO - 'USER': 'cltbld', 19:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:58 INFO - 'XPC_FLAGS': '0x0', 19:01:58 INFO - 'XPC_SERVICE_NAME': '0', 19:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 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)) 19:01:58 INFO - Cleaning up... 19:01:58 INFO - Return code: 0 19:01:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:01:58 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')]} 19:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:58 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')]} 19:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bfa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b70570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f15642c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, '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.lZDve1Fg2h/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.cypyd4GcrB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 19:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:59 INFO - 'HOME': '/Users/cltbld', 19:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:59 INFO - 'LOGNAME': 'cltbld', 19:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:59 INFO - 'MOZ_NO_REMOTE': '1', 19:01:59 INFO - 'NO_EM_RESTART': '1', 19:01:59 INFO - 'PAGER': '/bin/cat', 19:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:59 INFO - 'PWD': '/builds/slave/test', 19:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:59 INFO - 'SHELL': '/bin/bash', 19:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:01:59 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:59 INFO - 'USER': 'cltbld', 19:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:59 INFO - 'XPC_FLAGS': '0x0', 19:01:59 INFO - 'XPC_SERVICE_NAME': '0', 19:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:01:59 INFO - Cleaning up... 19:01:59 INFO - Return code: 0 19:01:59 INFO - Running post-action listener: _resource_record_post_action 19:01:59 INFO - Running post-action listener: _start_resource_monitoring 19:01:59 INFO - Starting resource monitoring. 19:01:59 INFO - [mozharness: 2017-02-03 03:01:59.434164Z] Finished create-virtualenv step (success) 19:01:59 INFO - [mozharness: 2017-02-03 03:01:59.435061Z] Running install step. 19:01:59 INFO - Running pre-action listener: _resource_record_pre_action 19:01:59 INFO - Running main action method: install 19:01:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:59 INFO - Reading from file tmpfile_stdout 19:01:59 INFO - Detecting whether we're running mozinstall >=1.0... 19:01:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:01:59 INFO - Reading from file tmpfile_stdout 19:01:59 INFO - Output received: 19:01:59 INFO - Usage: mozinstall [options] installer 19:01:59 INFO - Options: 19:01:59 INFO - -h, --help show this help message and exit 19:01:59 INFO - -d DEST, --destination=DEST 19:01:59 INFO - Directory to install application into. [default: 19:01:59 INFO - "/builds/slave/test"] 19:01:59 INFO - --app=APP Application being installed. [default: firefox] 19:01:59 INFO - mkdir: /builds/slave/test/build/application 19:01:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:02:21 INFO - Reading from file tmpfile_stdout 19:02:21 INFO - Output received: 19:02:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:02:21 INFO - Running post-action listener: _resource_record_post_action 19:02:21 INFO - [mozharness: 2017-02-03 03:02:21.304558Z] Finished install step (success) 19:02:21 INFO - [mozharness: 2017-02-03 03:02:21.304688Z] Running run-tests step. 19:02:21 INFO - Running pre-action listener: _resource_record_pre_action 19:02:21 INFO - Running main action method: run_tests 19:02:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:21 INFO - 2017-02-02 19:02:21.319 screenresolution[1903:14456] starting screenresolution argv=screenresolution get 19:02:21 INFO - 2017-02-02 19:02:21.321 screenresolution[1903:14456] Display 0: 1600x1200x32@60 19:02:21 INFO - 2017-02-02 19:02:21.327 screenresolution[1904:14459] starting screenresolution argv=screenresolution list 19:02:21 INFO - Available Modes on Display 0 19:02:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:21 INFO - Intel Iris: 19:02:21 INFO - Chipset Model: Intel Iris 19:02:21 INFO - Type: GPU 19:02:21 INFO - Bus: Built-In 19:02:21 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:21 INFO - Vendor: Intel (0x8086) 19:02:21 INFO - Device ID: 0x0a2e 19:02:21 INFO - Revision ID: 0x0009 19:02:21 INFO - Displays: 19:02:21 INFO - Display: 19:02:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:21 INFO - Display Serial Number: 1600x1200 60 19:02:21 INFO - Main Display: Yes 19:02:21 INFO - Mirror: Off 19:02:21 INFO - Online: Yes 19:02:21 INFO - Rotation: Supported 19:02:21 INFO - Return code: 0 19:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:21 INFO - Python 2.7.3 19:02:21 INFO - Return code: 0 19:02:21 INFO - grabbing minidump binary from tooltool 19:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:21 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 0x105a0ed30>, '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 0x105874db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a06b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:21 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 19:02:21 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 19:02:21 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 19:02:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:21 INFO - Return code: 0 19:02:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:02:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:02:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--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 19:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --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 19:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lZDve1Fg2h/Render', 19:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:21 INFO - 'HOME': '/Users/cltbld', 19:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:21 INFO - 'LOGNAME': 'cltbld', 19:02:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:21 INFO - 'MOZ_NO_REMOTE': '1', 19:02:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:21 INFO - 'NO_EM_RESTART': '1', 19:02:21 INFO - 'PAGER': '/bin/cat', 19:02:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:21 INFO - 'PWD': '/builds/slave/test', 19:02:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:21 INFO - 'SHELL': '/bin/bash', 19:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cypyd4GcrB/Listeners', 19:02:21 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 19:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:21 INFO - 'USER': 'cltbld', 19:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:21 INFO - 'XPC_FLAGS': '0x0', 19:02:21 INFO - 'XPC_SERVICE_NAME': '0', 19:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--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 19:02:21 INFO - mozversion application_buildid: 20170202162508 19:02:21 INFO - mozversion application_changeset: b9e7643b8be64976276573389670f70d57e1fe9f 19:02:21 INFO - mozversion application_display_name: Firefox 19:02:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:21 INFO - mozversion application_name: Firefox 19:02:21 INFO - mozversion application_remotingname: firefox 19:02:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:02:21 INFO - mozversion application_vendor: Mozilla 19:02:21 INFO - mozversion application_version: 52.0 19:02:21 INFO - mozversion platform_buildid: 20170202162508 19:02:21 INFO - mozversion platform_changeset: b9e7643b8be64976276573389670f70d57e1fe9f 19:02:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:02:21 INFO - mozversion platform_version: 52.0 19:02:21 INFO - using testdate: 1486090941 19:02:21 INFO - actual date: 1486090941 19:02:21 INFO - starting webserver on 'localhost:51004' 19:02:21 INFO - SUITE-START | Running 2 tests 19:02:21 INFO - TEST-START | dromaeo_css 19:02:21 INFO - operating with platform_type : mac_ 19:02:21 INFO - Initialising browser for dromaeo_css test... 19:02:21 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpRwJZYR/profile http://localhost:51004/getInfo.html) 19:02:24 INFO - PROCESS | 1914 | 2017-02-02 19:02:24.898 plugin-container[1917:14622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 19:02:24 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:24 INFO - PROCESS | 1914 | 2017-02-02 19:02:24.899 plugin-container[1917:14622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 19:02:24 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:24 INFO - PROCESS | 1914 | 2017-02-02 19:02:24.899 plugin-container[1917:14622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:02:24 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 19:02:24 INFO - PROCESS | 1914 | colorDepth:24 19:02:24 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 19:02:24 INFO - PROCESS | 1914 | __metrics 19:02:25 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 19:02:25 INFO - TEST-INFO | 1914: exit 0 19:02:25 INFO - Browser initialized. 19:02:25 INFO - Running cycle 1/1 for dromaeo_css test... 19:02:25 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpRwJZYR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:28 INFO - PROCESS | 1918 | 2017-02-02 19:02:28.195 plugin-container[1919:14750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 19:02:28 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:28 INFO - PROCESS | 1918 | 2017-02-02 19:02:28.196 plugin-container[1919:14750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 19:02:28 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:28 INFO - PROCESS | 1918 | 2017-02-02 19:02:28.196 plugin-container[1919:14750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:02:37 INFO - PROCESS | 1918 | RSS: Main: 177127424 19:02:37 INFO - PROCESS | 1918 | 19:05:40 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51004/tests/dromaeo/cssquery-ext.html) 19:05:42 INFO - PROCESS | 1918 | RSS: Main: 184549376 19:05:42 INFO - PROCESS | 1918 | 19:08:44 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-ext.html (next: http://localhost:51004/tests/dromaeo/cssquery-jquery.html) 19:08:45 INFO - PROCESS | 1918 | RSS: Main: 191873024 19:08:45 INFO - PROCESS | 1918 | 19:11:46 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51004/tests/dromaeo/cssquery-mootools.html) 19:11:47 INFO - PROCESS | 1918 | RSS: Main: 194482176 19:11:47 INFO - PROCESS | 1918 | 19:14:49 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51004/tests/dromaeo/cssquery-prototype.html) 19:14:50 INFO - PROCESS | 1918 | RSS: Main: 198668288 19:14:50 INFO - PROCESS | 1918 | 19:17:52 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51004/tests/dromaeo/cssquery-yui.html) 19:17:53 INFO - PROCESS | 1918 | RSS: Main: 201601024 19:17:53 INFO - PROCESS | 1918 | 19:20:55 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51004/tests/dromaeo/cssquery-yui.html (next: http://localhost:51004/tests/dromaeo/cssquery-dojo.html) 19:20:56 INFO - PROCESS | 1918 | RSS: Main: 204947456 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | __start_tp_report 19:20:56 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 19:20:56 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 19:20:56 INFO - PROCESS | 1918 | |i|pagename|runs| 19:20:56 INFO - PROCESS | 1918 | |0;dojo.html;3581.8925432237043;3592.1738000259943;3611.295797319524;3615.9819200904;3642.690371318438;5959.6424214547105;5972.970135149329;6007.339192688801;6022.066579680146;6050.909236361458;5985.551083668721;6063.696815159246;6076.513878889689;6079.848003799896;6096.908546371808;5375.462453754632;5419.160030195326;5419.430959749209;5432.6468779529205;5515.972420137894;5454.400015998237;5486.972565137169;5487;5487.917681234785;5515.586331025169;5331.360236771593;5338.252644629755;5390.434004429537;5477.04151773438;5488.067028605146;5380.596455265871;5416.052190866602;5421.186821976696;5426.538744206747;5441.183822426668;5203.713795741232;5370.5435038021815;5392.973035134799;5399.541039011689;5497.5601951843755;5031.823886164003;6113.23584551931;6131.41751533608;6143.569950103494;6147.10866924298;6165.660888651122;6222.222222222223;6291.370862913672;6312.715927783261;6354.777582784581;6202.751889924398;6253.593516421463;6317.210348706411;6321.936780632181;6369.808905732789;6076.088586711984;6096.329403765582;6102.938970610326;6122.602030867979;6128.662923539248;6449.193850768654;6556.147700798866;6566.507511936624;6588.571742836701;6601.933980660228;6155.96922015396;6172.074188871705;6229;6252.624842509464;6289.622622642656;6413.679316034273;6419.197600299963;6429;6435.74257029723;6439.066335381437;6179.351168127282;6292.716827742763;6300.464460520815;6306.306306306303;6317.147185129948;5065.138926382524;6196.659183744938;6207.937920620826;6219.502439804804;6461.709223084972;4282.743035417822;4351.955530672616;4556.270996640522;5092.719900405439;5095.949040509621;4579.5878370946775;4608.815647374135;4615.792289346988;4636.420447444069;4645.6980296280635;4620.792064357112;6022.45797878193;6025.6685882276015;6128.693565321716;6150;6409.262934762562;6430.228372595318;6435.388638079375;6445.548811583238;6458.741650334028;6004.369541198112;6196.411340922605;6220.284667263232;6225.28409232944;6234.625922444668;5497.285352904097;5534.920690465401;5551.861203469864;5590.133529302964;5599.524040456526;6575.243846957565;6582.703778330082;6587.374199451045;6589.901151482636;6599.703013364314;6425.389587989133;6425.5502114851515;6433.099366088845;6447.258565265054;6449.838754031187;6322.336154703874;6417.6470294132405;6503.869922601616;6524.543281970216;6626.801195964129;6312.084747711464;6336.20797400325;6339.270983836734;6345.460635846005;6363.3000369958345;6407.295197528175;6407.583507072025;6418.005209192583;6431.421172094534;6433.163688720437;6142.867137759864;6154.292256390393;6214.564980451506;6225.470834979077;6275.905861411991;6120.8775824484155;6125.203723515914;6177.135201071764;6184.443400094013;6244.687765611792;5138.820141295037;6134.110553969738;6155.169052178077;6234.096056071935;6252;6160.907586386118;6169.043798211284;6204.72078756448;6216.160818289654;6258.468030217483;6126.761967422209;6136.85608443425;6215.81352559424;6225.937740622717;6244.188255526752 19:20:56 INFO - PROCESS | 1918 | |1;ext.html;453.97276163430195;490.0272958178017;512.9128048231801;520.8801975545624;524.3131497737961;40155.799221003894;41744.165116697644;41779;43431.78284108583;43582.34626480597;42466.36300455496;43021.56978430215;43486.13027739443;43560.78219608906;43652.345214821806;9635.084666956645;9636.61453541861;9770.267229957746;9855.507224638775;9867.555959981783;9691.612335506572;9715.70852874415;9785.412875227508;9795.706128816148;9834.754131146743;9696.981816909194;9707.223422126213;9797.363171393872;9833.16418104462;9851.261155413336;9677.225821934227;9762.974887636803;9801.705948821547;9802;9846.803063938682;9596.136347728669;9660.468674222915;9728.90271097287;9770.462624555646;9801;40213.798931005156;41072.178556429004;41085.76742697724;41194.38208426876;41657.541986030345;3277.524758909945;3394.8811791587423;3443.707284880788;3453;3834.271488417192;27762.722372776214;30413.783448662027;30767.230819229473;31103.22241943947;31558.842205789053;3218.097181545595;3289.588801399825;3333.300006998509;3388.8983330500123;3415.129142068781;387336;388791.99999999715;389810;389910;390773;59350.70324648349;59554;59659.403405965386;59837.40162598405;59849.70075149596;59525.40474595285;60707;61064;61572;61957;14042.789358159636;16872.493825185265;16886.98678079319;16927.153642317833;16961.830381696273;13088.607341779762;13237;13270.071095023255;13293.800592991112;13304.800427993394;13630.863691363158;14578.63553411152;15643.530694078969;16227.918860405622;16317.347306107622;6883.7590684325805;6958.721651133898;7121.572705637678;7135.500514963903;7166.390856777232;798.4211446701212;997.7505623594102;1008.1430783833829;1036.4196050211906;1079.2984560036039;30208.54687179694;30401.391972160432;30852.382952340828;31113.844430777703;31146.532802007987;1055.7096798380353;1109.0573012938937;1122;1128.7911736328806;1152.8040233160382;15330.926835121434;15837.841621583868;15929.761053584205;15946.362145514282;16274.023558586523;694398;694585;694665;696278;700973;38883;41594;44417.33373999393;44449;44493.55506444894;1397.958061258164;1402.4810819996667;1427.2863568215892;1430.9642258943609;1433.2690327933037;25034.74965250324;29694.257643559085;30236.24409389783;30333.848330759087;30448.543271851384;50481.74759126181;50789;50846.49153508418;50875;51113.744431277606;631729;635195;636705;638708;639522;26618.467630647665;26993.865030674722;27213.591796122677;27532.311692207855;27604.723952760214;46918.76540617275;47453.76273118612;47879.76060119677;48531.757341213066;48846;38839.14111591076;39080.41379379368;39251.214975700896;40162.79918600388;40657.780266592046;38836.41745373765;39208.411873822486;39522;40054.59808906674;40419.98950026273;37995.050123747125;38993.41509877411;39232.80383598064;39282.607173929035;39307.82076537709;34002.659973400936;34277.31445371128;35321.293574127856;35434.29131417409;35964;20207.191712331343;20343.69484457764;21899;25978.480430391664;26030 19:20:56 INFO - PROCESS | 1918 | |2;jquery.html;379.6165872468807;385.9445303192905;388.64633183802744;389.98050097495127;414.9750117210491;19700.310489132884;20381.388558343275;20942.26702065428;21119.788802111972;21179.68230476542;20756.584868302663;20959.58080838386;21042.263520776774;21109.788902110973;21193.470163245926;5627.296587926509;5959.255093113361;5995.850103747398;6029.306629737571;6107.175531303262;5926.792562260322;5952.70236488176;6069.848253793646;6090.3909609038965;6164.383561643845;5297.205419187114;5848.766049358021;5910.024845900442;6070.210872586557;6071.544634152434;5805.36141024487;5956.880862382728;5994.310654274749;6052.122442245894;6055.152278680988;5159.793608255665;5159.845204643829;5254.579633629339;5279.841604751825;5317.8404647860625;25821.61267580969;25950;25966.610500842504;26066;26770.063047793235;2666.2401215653445;2786.9442611147865;2818.957715634267;3210.1011716719354;3227.596550431196;15175.62060948474;16011.91994040034;16161.03033817963;16174.919125404298;16676.16619169037;1910.484169274302;1971.5465442948203;1984.7958275534527;2012.11466954541;2018.3743039657606;115791;119999;120723;120772.1305082538;134133;9298.53507324631;9643.517824108767;11738.708742038369;11869;11922.821157682642;14826.184559849096;15201;15203.847961520243;15219.162946037854;15222.010569312959;8701.086385929386;8762.561871906506;8846.115388461103;8880.422772519765;8977.192052715156;7098.3256590623805;8749.518776467356;8960.372773905765;8966.193042626195;8979.640814367353;4635.7218566886095;4652.13935421948;4667.463241727177;4670.929936050962;4695.2252878275385;1982.5440148765865;2011.2055737983414;2011.919523219084;2012.577358754678;2014.7380840490641;858.7209157023992;881.1100788203833;883.960221790021;967.4292167621137;985.8077674853478;21100.05049772764;21855.344339669835;21903.561928761337;21934.341969740934;21960.34118976433;810.9107998120202;918.1231923513029;972.2416515118077;978.4667356290838;984.975375615601;8374.832503349899;11455.140864435201;11595;11624.94187529057;11637;602959;611151;611612;616031;619108;102939;123409.76590234162;133277.33361333326;134209;134734;960.6109525642127;961.8749562556962;989.6338354808767;990.6235630460379;996.5465713100522;19045;19392.612147756685;19535;19547.8045219546;19744;46524;53542.46457535375;56054;56416.717916410154;56444.43555564556;60405.39594604173;80598;80938.19061809307;83808;84588;6803.829904252433;7947.84104317922;8146.185381461806;8229.506229626242;8234.505929644241;10639.787204256027;12083.879161208275;12267.325297108744;12388.132830701765;12501.187422817487;10011.899881001096;10540.262181647211;10647.30792498485;10775.730606734895;10964.396958167377;8018.879716804136;8677.915260592426;8885.80041694363;9868;10497.79004419892;8671.566421678765;9522.666706665234;10596.993285637853;10985.505652245509;11261.155413344195;6491.383318584727;8003.159668234752;8057.113717491188;8079.515229086273;8093.716719914774;9890.357126786736;10809.35143891369;10900.563977440814;10989.835152472559;11188.496517656888 19:20:56 INFO - PROCESS | 1918 | |3;mootools.html;1505.7666061760428;1548.5276990517893;1620.5300462865762;1825.8356747892694;1847.2333981397721;6911.2743161967965;7601.505902116359;7857.21427857214;8075.232852878982;8722.127787221283;8308.667653293862;8579.270761985239;8595.097514760939;8707.825843483126;8718.17177368149;2366.337425520857;2379.8334116611845;2402.4474370894704;2426.915057972971;2440.7437219092008;2425.9514809703796;2428.781409673129;2447.094575007241;2464.125235541384;2468;2384.511175209078;2392.605220138675;2407.181558270188;2420.8668523231217;2439.8048156147556;2344.542814151241;2370.3481542575755;2375.3705268103968;2382.7617238276207;2390.665306857024;2402.0992127952018;2439.902403903842;2441.4872876695913;2453.4234454903085;2455.877206139704;8609.096044915319;8609.182127697859;8610.182032706884;8670.913290867074;8776.517291548962;5256.421793602701;5331.306930099101;5334.786608535693;5413.675179489243;5440.238366628675;3544.344296305166;3598.5501812273465;3627.292677927805;3633.1280492681567;3663.1757854482794;1074.2426589254558;1123.6404350607809;1144.3705961721103;1147.9196456248067;1674.8911320764232;11137.554497820158;11235.04502117321;11368.317900925971;11424.657260282205;11437.828432573519;4394.780260986938;4594.839180628662;4601.309803529497;4641.628669706415;4659.953400465952;7980.561069141137;8929.285657147542;8967.372283940193;8980.775480612907;9000.414973026733;5178.6374953752875;5216.7913283469;5226.869328266747;5245.501677340647;5248.475152484721;4139.606737359946;4434.445694288214;4450.443694538183;4938.678985865908;4951.257311403247;3144.9528257076163;3864.5362556492837;3883.3398322284716;3892.3188442022533;3953.2093581283857;990.4898977026837;1199.7900367435664;1200.591798788416;1202.2305724336431;1202.272625061825;866.7963028688254;879.8020445399734;886.3972498700816;895.4403497813867;898.8112496375834;5804.912926306109;5805.238801454326;5876.353601103875;5906.49794767441;6059.091136329498;946.8059047895165;951.2960409297209;963.643451922793;967.7048500207363;998.565623953568;3621.402468592653;3741.0955782548754;3770.811459427018;3788.0151160698415;3795.899189235146;10184;10486.161107111413;10541.525631346607;10557.577696892038;10557.9472102639;10260.948695256626;10469.214808889365;10470.633527826489;10492.265541412027;10506;7931.603419828985;8050.154733752989;8075.232852878967;8205.974253218466;8219.712310069119;4273.124009578091;4721.537605342705;5059.114654935298;5443.142236203582;5598.188262701804;9336.719898403058;9343.579538920902;9548;9632.27757918173;9637.37357071788;8026.317762990211;8976.833011708437;9780.070893265129;9907.157891579009;9945.353552019096;6846.863062738617;7031.472639551952;7164.749233776793;7281.854362912818;7446.292602202782;7921.128675845767;8053.798655033671;8057.315128214167;8226.259636632733;8235.95882020586;7235.782926512213;7345.632718363953;7395.667194976347;7468.477206595703;7499.925000749922;7132.179799322937;7228.602426866572;7241.058662373857;7242.746503872339;7270.200277969525;6708.362705542965;6716.496262780409;6808.489363297674;6816.693248803915;6832.316768323128;4691.577758001797;4759.50025247344;4776.546228108324;4812.494688057704;4870.439899411613;3937.31097058008;4612.123696497655;4661.463931647904;4794.856154315376;4867.245576935581 19:20:56 INFO - PROCESS | 1918 | |4;prototype.html;308.57262691172735;311.7350252285557;318.9888353907612;320.7722517012921;321.21463022908966;4519;4660.9512859606575;4974.57716094132;5368.698250575897;5558.749856256468;4291.957080429194;4651.743904206457;4862.440819305781;5350.876315973641;5437;4091.1204091120403;4183.4770653668365;4237.173751118533;4238;4263.507772279693;4162.854300099497;4277.572242775713;4288.399624052635;4292.356146578007;4315.590018948211;3669.3395188865993;4174.996001919066;4207.326827707568;4211.494620645527;4269.6370808481315;3888.747231429948;3995.9200815983663;4224.767637779921;4228.830846766125;4242.596953289463;3695.63043695631;3696.9630303696886;3715.2383761329133;3724.4971928789537;3752.699784017272;4705.835295764632;5168;5311.415744268127;5406.864828379283;5483.369412517572;4888.01830883161;4896.135760580065;4930.654854160211;4932.8273510427325;4940.419065898878;5620.634658747168;5887.8233652990475;5900.262467191601;5908.586398952075;5948.494377977877;3518.5074089627474;3800.011996880803;4042.4946881639794;4086.4687590613325;4097.9100658664;5051.545360917498;5882.47057764799;6107.236595425572;6125.418085281891;6157.7229024694;3486.9825650871585;3653.8355773990234;4142.316517774533;4156.147989662112;4170.249355116048;4034.5677284564144;4142.399352093989;4200.684948628804;4202.70581059329;4223.184030866695;4870;4888.120138375056;4907.214845624683;4909.165441874889;4915.68048076881;5718.79984200551;5904.61619994699;5918.8816223675285;5948.375420580864;6004.118023348817;1147.7718840840387;1176.4588289386807;1198.0295960272033;1377.7726675098697;1377.9931100344434;747.8990336304638;820.9630566624517;906.3066753933247;907.8229745199623;927.4991504587447;4406.984466203673;4628.038055644391;4645.512221216791;5383.327084114486;5406.189071639207;4923.852284431473;5529.336479622471;5893.941060589339;5896.204012458349;5896.3219229788265;4697.9429628333355;5013.724245166551;5312.015988008993;5339.519443250126;5396.541293990055;2787.5539913613725;2818.6758522769733;2976.4968690811;3085.7222849943614;3099.4038070393767;5293.570735901285;5998.520118390518;6040.607360521552;6072.514198864169;6139.508839292846;5826.727576245267;5968.671723055274;6309.463695585927;6358.491320694333;6373.012183111625;5308.858595402007;5403.351597808288;6284.874302514016;6435.356464353621;6471.741130354827;5960.781973433598;6045.697715114139;6119.969400152971;6201.472874805511;6240;6062.939370606414;6090.817275481742;6096.695165241631;6105.542084343603;6121.84695382619;4895.975520122377;5336.332958380202;5698.1737648041635;6353.491720662243;6377.333033505845;3814.713896457721;3929.3713005918917;3994.920101597894;4109.671226301829;4119.938200926928;6145.432914606926;6150.815475535741;6151.523634327639;6164;6176.598521096114;4934.383202099738;5002.874928126826;5481.917771233514;5607.43925607436;5938.61399009063;4891.877703057452;5153.665011774223;5477.20580515815;5918.348981611933;5933.2880054393745;5058.570021548209;5474.425185355481;5787.508061814793;5831.413680586324;5859.707014649165;4938.777755000961;5746.569007324551;5859.707014649336;5942.702864856827;5952.464278214981;5726.564061563029;5788.623739456922;5815.883682326415;5834.795782147605;5844.47399734026 19:20:56 INFO - PROCESS | 1918 | |5;yui.html;699.381047772721;768.9000429944107;777.4907435629661;791.6318911706055;814.2061490047201;15000.249987500622;15064.171470569141;15396.846031539695;15709.057456552615;15736;13827.170369777821;13829.515966941159;13984.580462586113;14034.929825350859;14241.71516569668;838.6477679374677;855.2388374346821;868.288003836854;869.0961400143843;871.8212766382908;838.4298676899706;852.0244320253306;854.0605334132435;855.7389996051202;856.8414843253987;892.3040028777564;900.4102312985005;904.1275169461468;910.9726708198732;913.9854761215079;896.9551522423918;897.3045889435707;897.6409436225497;898.137787723786;900.3787386703186;666.1373521289893;669.7823207457595;678.4402867634172;681.0567364200568;684.3806355248508;12495.312757798318;12802.551910683174;12821.743565128738;12894.290814005226;13117.934410327887;9634.71095867125;11416.915393037649;12112.515499380013;12123.939380303042;12215.052481508548;9482.905170948272;10684.893151068545;10916.617918372898;10974.890251097546;11067.335959842436;3655.2872189922978;3684.5578530576504;3705.6479634434686;3753.530808648919;3845.5193100862393;86129.13870861274;89221;94047.05952940452;94481;96009;8656.350773691998;8969.686060987835;9168.862467063;9213.723588292361;9483.14651681352;18629.44111676652;18912;19075.42773716763;19211.423657290023;19225.51936201606;7817.296443320015;7866.9213307866185;7964.8805267921025;8020.2781749641645;8101.513909165469;4401.933970990438;4431.889202769892;4443.355713421601;4521.547845215518;4539.114872599877;3391.9491207631904;3501.77238479498;3507.859685612598;3543.645635436436;3546.0957455848593;634.6572850660684;638.4254171245916;644.4103645164715;648.597869321024;649.4804156674642;615.5875563372551;621.8227805075622;660.7687309441657;673.4275865514273;687.8693048320803;8284.005919289602;8325.666973321;8954.955225223832;9069.59186836594;9160.6793762218;543.6466296906963;559.5965328912331;622.9503286961484;664.3356643356643;683.8016975077271;4472.262076757299;4633.2818413146015;4645.976770116128;4673.0920027395305;4809.2305231162845;76132.23867761252;99190.00809991952;101397.49301253447;108806;109375.81248375148;62899;63446.0483092754;77277;87850.56074719585;102623;46880.5311946883;70687.2931270691;77551.22448775553;83082.58458707668;84749;9370.484623345781;9868.654597089067;9870.407775533491;9886;9912.553935073083;43844.56155438533;46901;54799.45200548103;56116.75532978635;56898;74991.62504187445;80140;80796.59601701953;83186.58406707928;84220;12395.938020310201;12486.625401237978;12564;12661.810072849099;12786.808197876853;13851;14424.711505770036;14455.927720361751;14532.122488722967;14725.190114543802;13077.280749558866;13607.86392136066;13626.727465450835;13915.652108697364;14075.155490670593;11293.040091592307;13239.602811915658;13419.530316439268;13587.954825645138;14165.575032749033;12609;12736.74526509483;13034.543790967271;13192.076554641082;13369.570637809493;10769.784604308026;10806.729831754268;11267.380294083903;11308.151888608223;11528.423578820857;10899.509522071367;11046.558137674725;11205.775884482102;11316.490757916079;11357.432128393382 19:20:56 INFO - PROCESS | 1918 | __end_tp_report 19:20:56 INFO - PROCESS | 1918 | __start_cc_report 19:20:56 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,354 19:20:56 INFO - PROCESS | 1918 | __end_cc_report 19:20:56 INFO - PROCESS | 1918 | __startTimestamp1486092056164__endTimestamp 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | ------- Summary: start ------- 19:20:56 INFO - PROCESS | 1918 | Number of tests: 6 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5913.16 Median:6160.91 stddev:663.04 (10.8%) stddev-sans-first:639.35 19:20:56 INFO - PROCESS | 1918 | Values: 3581.9 3592.2 3611.3 3616.0 3642.7 5959.6 5973.0 6007.3 6022.1 6050.9 5985.6 6063.7 6076.5 6079.8 6096.9 5375.5 5419.2 5419.4 5432.6 5516.0 5454.4 5487.0 5487.0 5487.9 5515.6 5331.4 5338.3 5390.4 5477.0 5488.1 5380.6 5416.1 5421.2 5426.5 5441.2 5203.7 5370.5 5393.0 5399.5 5497.6 5031.8 6113.2 6131.4 6143.6 6147.1 6165.7 6222.2 6291.4 6312.7 6354.8 6202.8 6253.6 6317.2 6321.9 6369.8 6076.1 6096.3 6102.9 6122.6 6128.7 6449.2 6556.1 6566.5 6588.6 6601.9 6156.0 6172.1 6229.0 6252.6 6289.6 6413.7 6419.2 6429.0 6435.7 6439.1 6179.4 6292.7 6300.5 6306.3 6317.1 5065.1 6196.7 6207.9 6219.5 6461.7 4282.7 4352.0 4556.3 5092.7 5095.9 4579.6 4608.8 4615.8 4636.4 4645.7 4620.8 6022.5 6025.7 6128.7 6150.0 6409.3 6430.2 6435.4 6445.5 6458.7 6004.4 6196.4 6220.3 6225.3 6234.6 5497.3 5534.9 5551.9 5590.1 5599.5 6575.2 6582.7 6587.4 6589.9 6599.7 6425.4 6425.6 6433.1 6447.3 6449.8 6322.3 6417.6 6503.9 6524.5 6626.8 6312.1 6336.2 6339.3 6345.5 6363.3 6407.3 6407.6 6418.0 6431.4 6433.2 6142.9 6154.3 6214.6 6225.5 6275.9 6120.9 6125.2 6177.1 6184.4 6244.7 5138.8 6134.1 6155.2 6234.1 6252.0 6160.9 6169.0 6204.7 6216.2 6258.5 6126.8 6136.9 6215.8 6225.9 6244.2 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:70313.31 Median:40105.20 stddev:158018.35 (394.0%) stddev-sans-first:158374.58 19:20:56 INFO - PROCESS | 1918 | Values: 454.0 490.0 512.9 520.9 524.3 40155.8 41744.2 41779.0 43431.8 43582.3 42466.4 43021.6 43486.1 43560.8 43652.3 9635.1 9636.6 9770.3 9855.5 9867.6 9691.6 9715.7 9785.4 9795.7 9834.8 9697.0 9707.2 9797.4 9833.2 9851.3 9677.2 9763.0 9801.7 9802.0 9846.8 9596.1 9660.5 9728.9 9770.5 9801.0 40213.8 41072.2 41085.8 41194.4 41657.5 3277.5 3394.9 3443.7 3453.0 3834.3 27762.7 30413.8 30767.2 31103.2 31558.8 3218.1 3289.6 3333.3 3388.9 3415.1 387336.0 388792.0 389810.0 389910.0 390773.0 59350.7 59554.0 59659.4 59837.4 59849.7 59525.4 60707.0 61064.0 61572.0 61957.0 14042.8 16872.5 16887.0 16927.2 16961.8 13088.6 13237.0 13270.1 13293.8 13304.8 13630.9 14578.6 15643.5 16227.9 16317.3 6883.8 6958.7 7121.6 7135.5 7166.4 798.4 997.8 1008.1 1036.4 1079.3 30208.5 30401.4 30852.4 31113.8 31146.5 1055.7 1109.1 1122.0 1128.8 1152.8 15330.9 15837.8 15929.8 15946.4 16274.0 694398.0 694585.0 694665.0 696278.0 700973.0 38883.0 41594.0 44417.3 44449.0 44493.6 1398.0 1402.5 1427.3 1431.0 1433.3 25034.7 29694.3 30236.2 30333.8 30448.5 50481.7 50789.0 50846.5 50875.0 51113.7 631729.0 635195.0 636705.0 638708.0 639522.0 26618.5 26993.9 27213.6 27532.3 27604.7 46918.8 47453.8 47879.8 48531.8 48846.0 38839.1 39080.4 39251.2 40162.8 40657.8 38836.4 39208.4 39522.0 40054.6 40420.0 37995.1 38993.4 39232.8 39282.6 39307.8 34002.7 34277.3 35321.3 35434.3 35964.0 20207.2 20343.7 21899.0 25978.5 26030.0 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:35655.49 Median:4643.93 stddev:102103.72 (2198.6%) stddev-sans-first:102355.79 19:20:56 INFO - PROCESS | 1918 | Values: 379.6 385.9 388.6 390.0 415.0 19700.3 20381.4 20942.3 21119.8 21179.7 20756.6 20959.6 21042.3 21109.8 21193.5 5627.3 5959.3 5995.9 6029.3 6107.2 5926.8 5952.7 6069.8 6090.4 6164.4 5297.2 5848.8 5910.0 6070.2 6071.5 5805.4 5956.9 5994.3 6052.1 6055.2 5159.8 5159.8 5254.6 5279.8 5317.8 25821.6 25950.0 25966.6 26066.0 26770.1 2666.2 2786.9 2819.0 3210.1 3227.6 15175.6 16011.9 16161.0 16174.9 16676.2 1910.5 1971.5 1984.8 2012.1 2018.4 115791.0 119999.0 120723.0 120772.1 134133.0 9298.5 9643.5 11738.7 11869.0 11922.8 14826.2 15201.0 15203.8 15219.2 15222.0 8701.1 8762.6 8846.1 8880.4 8977.2 7098.3 8749.5 8960.4 8966.2 8979.6 4635.7 4652.1 4667.5 4670.9 4695.2 1982.5 2011.2 2011.9 2012.6 2014.7 858.7 881.1 884.0 967.4 985.8 21100.1 21855.3 21903.6 21934.3 21960.3 810.9 918.1 972.2 978.5 985.0 8374.8 11455.1 11595.0 11624.9 11637.0 602959.0 611151.0 611612.0 616031.0 619108.0 102939.0 123409.8 133277.3 134209.0 134734.0 960.6 961.9 989.6 990.6 996.5 19045.0 19392.6 19535.0 19547.8 19744.0 46524.0 53542.5 56054.0 56416.7 56444.4 60405.4 80598.0 80938.2 83808.0 84588.0 6803.8 7947.8 8146.2 8229.5 8234.5 10639.8 12083.9 12267.3 12388.1 12501.2 10011.9 10540.3 10647.3 10775.7 10964.4 8018.9 8677.9 8885.8 9868.0 10497.8 8671.6 9522.7 10597.0 10985.5 11261.2 6491.4 8003.2 8057.1 8079.5 8093.7 9890.4 10809.4 10900.6 10989.8 11188.5 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:5520.18 Median:5005.19 stddev:3035.59 (60.6%) stddev-sans-first:3029.12 19:20:56 INFO - PROCESS | 1918 | Values: 1505.8 1548.5 1620.5 1825.8 1847.2 6911.3 7601.5 7857.2 8075.2 8722.1 8308.7 8579.3 8595.1 8707.8 8718.2 2366.3 2379.8 2402.4 2426.9 2440.7 2426.0 2428.8 2447.1 2464.1 2468.0 2384.5 2392.6 2407.2 2420.9 2439.8 2344.5 2370.3 2375.4 2382.8 2390.7 2402.1 2439.9 2441.5 2453.4 2455.9 8609.1 8609.2 8610.2 8670.9 8776.5 5256.4 5331.3 5334.8 5413.7 5440.2 3544.3 3598.6 3627.3 3633.1 3663.2 1074.2 1123.6 1144.4 1147.9 1674.9 11137.6 11235.0 11368.3 11424.7 11437.8 4394.8 4594.8 4601.3 4641.6 4660.0 7980.6 8929.3 8967.4 8980.8 9000.4 5178.6 5216.8 5226.9 5245.5 5248.5 4139.6 4434.4 4450.4 4938.7 4951.3 3145.0 3864.5 3883.3 3892.3 3953.2 990.5 1199.8 1200.6 1202.2 1202.3 866.8 879.8 886.4 895.4 898.8 5804.9 5805.2 5876.4 5906.5 6059.1 946.8 951.3 963.6 967.7 998.6 3621.4 3741.1 3770.8 3788.0 3795.9 10184.0 10486.2 10541.5 10557.6 10557.9 10260.9 10469.2 10470.6 10492.3 10506.0 7931.6 8050.2 8075.2 8206.0 8219.7 4273.1 4721.5 5059.1 5443.1 5598.2 9336.7 9343.6 9548.0 9632.3 9637.4 8026.3 8976.8 9780.1 9907.2 9945.4 6846.9 7031.5 7164.7 7281.9 7446.3 7921.1 8053.8 8057.3 8226.3 8236.0 7235.8 7345.6 7395.7 7468.5 7499.9 7132.2 7228.6 7241.1 7242.7 7270.2 6708.4 6716.5 6808.5 6816.7 6832.3 4691.6 4759.5 4776.5 4812.5 4870.4 3937.3 4612.1 4661.5 4794.9 4867.2 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4726.56 Median:5160.83 stddev:1473.51 (28.6%) stddev-sans-first:1439.85 19:20:56 INFO - PROCESS | 1918 | Values: 308.6 311.7 319.0 320.8 321.2 4519.0 4661.0 4974.6 5368.7 5558.7 4292.0 4651.7 4862.4 5350.9 5437.0 4091.1 4183.5 4237.2 4238.0 4263.5 4162.9 4277.6 4288.4 4292.4 4315.6 3669.3 4175.0 4207.3 4211.5 4269.6 3888.7 3995.9 4224.8 4228.8 4242.6 3695.6 3697.0 3715.2 3724.5 3752.7 4705.8 5168.0 5311.4 5406.9 5483.4 4888.0 4896.1 4930.7 4932.8 4940.4 5620.6 5887.8 5900.3 5908.6 5948.5 3518.5 3800.0 4042.5 4086.5 4097.9 5051.5 5882.5 6107.2 6125.4 6157.7 3487.0 3653.8 4142.3 4156.1 4170.2 4034.6 4142.4 4200.7 4202.7 4223.2 4870.0 4888.1 4907.2 4909.2 4915.7 5718.8 5904.6 5918.9 5948.4 6004.1 1147.8 1176.5 1198.0 1377.8 1378.0 747.9 821.0 906.3 907.8 927.5 4407.0 4628.0 4645.5 5383.3 5406.2 4923.9 5529.3 5893.9 5896.2 5896.3 4697.9 5013.7 5312.0 5339.5 5396.5 2787.6 2818.7 2976.5 3085.7 3099.4 5293.6 5998.5 6040.6 6072.5 6139.5 5826.7 5968.7 6309.5 6358.5 6373.0 5308.9 5403.4 6284.9 6435.4 6471.7 5960.8 6045.7 6120.0 6201.5 6240.0 6062.9 6090.8 6096.7 6105.5 6121.8 4896.0 5336.3 5698.2 6353.5 6377.3 3814.7 3929.4 3994.9 4109.7 4119.9 6145.4 6150.8 6151.5 6164.0 6176.6 4934.4 5002.9 5481.9 5607.4 5938.6 4891.9 5153.7 5477.2 5918.3 5933.3 5058.6 5474.4 5787.5 5831.4 5859.7 4938.8 5746.6 5859.7 5942.7 5952.5 5726.6 5788.6 5815.9 5834.8 5844.5 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:19609.65 Median:27671.55 stddev:28181.83 (101.8%) stddev-sans-first:28225.12 19:20:56 INFO - PROCESS | 1918 | Values: 699.4 768.9 777.5 791.6 814.2 15000.2 15064.2 15396.8 15709.1 15736.0 13827.2 13829.5 13984.6 14034.9 14241.7 838.6 855.2 868.3 869.1 871.8 838.4 852.0 854.1 855.7 856.8 892.3 900.4 904.1 911.0 914.0 897.0 897.3 897.6 898.1 900.4 666.1 669.8 678.4 681.1 684.4 12495.3 12802.6 12821.7 12894.3 13117.9 9634.7 11416.9 12112.5 12123.9 12215.1 9482.9 10684.9 10916.6 10974.9 11067.3 3655.3 3684.6 3705.6 3753.5 3845.5 86129.1 89221.0 94047.1 94481.0 96009.0 8656.4 8969.7 9168.9 9213.7 9483.1 18629.4 18912.0 19075.4 19211.4 19225.5 7817.3 7866.9 7964.9 8020.3 8101.5 4401.9 4431.9 4443.4 4521.5 4539.1 3391.9 3501.8 3507.9 3543.6 3546.1 634.7 638.4 644.4 648.6 649.5 615.6 621.8 660.8 673.4 687.9 8284.0 8325.7 8955.0 9069.6 9160.7 543.6 559.6 623.0 664.3 683.8 4472.3 4633.3 4646.0 4673.1 4809.2 76132.2 99190.0 101397.5 108806.0 109375.8 62899.0 63446.0 77277.0 87850.6 102623.0 46880.5 70687.3 77551.2 83082.6 84749.0 9370.5 9868.7 9870.4 9886.0 9912.6 43844.6 46901.0 54799.5 56116.8 56898.0 74991.6 80140.0 80796.6 83186.6 84220.0 12395.9 12486.6 12564.0 12661.8 12786.8 13851.0 14424.7 14455.9 14532.1 14725.2 13077.3 13607.9 13626.7 13915.7 14075.2 11293.0 13239.6 13419.5 13588.0 14165.6 12609.0 12736.7 13034.5 13192.1 13369.6 10769.8 10806.7 11267.4 11308.2 11528.4 10899.5 11046.6 11205.8 11316.5 11357.4 19:20:56 INFO - PROCESS | 1918 | -------- Summary: end -------- 19:20:56 INFO - PROCESS | 1918 | 19:20:56 INFO - TEST-INFO | 1918: exit 0 19:20:56 INFO - TEST-OK | dromaeo_css | took 1114820ms 19:20:56 INFO - TEST-START | kraken 19:20:56 INFO - operating with platform_type : mac_ 19:20:56 INFO - Initialising browser for kraken test... 19:20:56 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp4zx1YM/profile http://localhost:51004/getInfo.html) 19:20:59 INFO - PROCESS | 1923 | 2017-02-02 19:20:59.406 plugin-container[1925:16642] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 19:20:59 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:59 INFO - PROCESS | 1923 | 2017-02-02 19:20:59.407 plugin-container[1925:16642] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:20:59 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:59 INFO - PROCESS | 1923 | 2017-02-02 19:20:59.407 plugin-container[1925:16642] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:20:59 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 19:20:59 INFO - PROCESS | 1923 | colorDepth:24 19:20:59 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 19:20:59 INFO - PROCESS | 1923 | __metrics 19:20:59 ERROR - PROCESS | 1923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 19:21:00 INFO - TEST-INFO | 1923: exit 0 19:21:00 INFO - Browser initialized. 19:21:00 INFO - Running cycle 1/1 for kraken test... 19:21:00 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp4zx1YM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:21:02 INFO - PROCESS | 1926 | 2017-02-02 19:21:02.900 plugin-container[1927:16766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:21:02 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:02 INFO - PROCESS | 1926 | 2017-02-02 19:21:02.901 plugin-container[1927:16766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.CFPasteboardClient' 19:21:02 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:02 INFO - PROCESS | 1926 | 2017-02-02 19:21:02.901 plugin-container[1927:16766] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:21:12 INFO - PROCESS | 1926 | RSS: Main: 177614848 19:21:12 INFO - PROCESS | 1926 | 19:21:15 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51004/tests/kraken/driver.html?testName=audio-beat-detection) 19:21:16 INFO - PROCESS | 1926 | RSS: Main: 190464000 19:21:16 INFO - PROCESS | 1926 | 19:21:19 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51004/tests/kraken/driver.html?testName=audio-dft) 19:21:19 INFO - PROCESS | 1926 | RSS: Main: 199315456 19:21:19 INFO - PROCESS | 1926 | 19:21:23 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51004/tests/kraken/driver.html?testName=audio-fft) 19:21:23 INFO - PROCESS | 1926 | RSS: Main: 202321920 19:21:23 INFO - PROCESS | 1926 | 19:21:25 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51004/tests/kraken/driver.html?testName=audio-oscillator) 19:21:26 INFO - PROCESS | 1926 | RSS: Main: 202313728 19:21:26 INFO - PROCESS | 1926 | 19:21:28 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51004/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:28 INFO - PROCESS | 1926 | RSS: Main: 202366976 19:21:28 INFO - PROCESS | 1926 | 19:21:31 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51004/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:32 INFO - PROCESS | 1926 | RSS: Main: 207605760 19:21:32 INFO - PROCESS | 1926 | 19:21:35 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51004/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:35 INFO - PROCESS | 1926 | RSS: Main: 208179200 19:21:35 INFO - PROCESS | 1926 | 19:21:38 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51004/tests/kraken/driver.html?testName=json-parse-financial) 19:21:39 INFO - PROCESS | 1926 | RSS: Main: 207572992 19:21:39 INFO - PROCESS | 1926 | 19:21:40 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51004/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:21:41 INFO - PROCESS | 1926 | RSS: Main: 206643200 19:21:41 INFO - PROCESS | 1926 | 19:21:43 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:21:44 INFO - PROCESS | 1926 | RSS: Main: 207155200 19:21:44 INFO - PROCESS | 1926 | 19:21:46 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:21:46 INFO - PROCESS | 1926 | RSS: Main: 206704640 19:21:46 INFO - PROCESS | 1926 | 19:21:49 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:21:50 INFO - PROCESS | 1926 | RSS: Main: 206589952 19:21:50 INFO - PROCESS | 1926 | 19:21:53 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:21:53 INFO - PROCESS | 1926 | RSS: Main: 207077376 19:21:53 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51004/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51004/tests/kraken/driver.html?testName=ai-astar) 19:21:55 INFO - PROCESS | 1926 | RSS: Main: 209031168 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | __start_tp_report 19:21:55 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 19:21:55 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 19:21:55 INFO - PROCESS | 1926 | |i|pagename|runs| 19:21:55 INFO - PROCESS | 1926 | |0;ai-astar;106;118;119;140;171;157;118;119;118;118 19:21:55 INFO - PROCESS | 1926 | |1;audio-beat-detection;146;109;108;138;112;116;112;109;136;114 19:21:55 INFO - PROCESS | 1926 | |2;audio-dft;196;198;195;201;197;199;223;275;245;191 19:21:55 INFO - PROCESS | 1926 | |3;audio-fft;76;72;81;76;83;50;56;66;100;81 19:21:55 INFO - PROCESS | 1926 | |4;audio-oscillator;92;69;87;70;69;70;84;68;65;85 19:21:55 INFO - PROCESS | 1926 | |5;imaging-gaussian-blur;91;89;91;87;92;80;130;131;92;88 19:21:55 INFO - PROCESS | 1926 | |6;imaging-darkroom;152;96;96;95;96;96;96;94;152;132 19:21:55 INFO - PROCESS | 1926 | |7;imaging-desaturate;90;89;88;86;137;135;88;88;87;89 19:21:55 INFO - PROCESS | 1926 | |8;json-parse-financial;55;53;56;58;59;61;60;58;59;58 19:21:55 INFO - PROCESS | 1926 | |9;json-stringify-tinderbox;67;76;71;82;79;74;71;71;70;102 19:21:55 INFO - PROCESS | 1926 | |10;stanford-crypto-aes;86;83;82;84;83;89;80;85;81;90 19:21:55 INFO - PROCESS | 1926 | |11;stanford-crypto-ccm;153;158;152;140;140;141;141;138;180;141 19:21:55 INFO - PROCESS | 1926 | |12;stanford-crypto-pbkdf2;162;169;163;164;165;168;171;162;186;207 19:21:55 INFO - PROCESS | 1926 | |13;stanford-crypto-sha256-iterative;69;67;68;67;67;67;68;68;66;68 19:21:55 INFO - PROCESS | 1926 | __end_tp_report 19:21:55 INFO - PROCESS | 1926 | __start_cc_report 19:21:55 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,906 19:21:55 INFO - PROCESS | 1926 | __end_cc_report 19:21:55 INFO - PROCESS | 1926 | __startTimestamp1486092115829__endTimestamp 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | ------- Summary: start ------- 19:21:55 INFO - PROCESS | 1926 | Number of tests: 14 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#0] ai-astar Cycles:10 Average:128.40 Median:119.00 stddev:20.76 (17.4%) stddev-sans-first:20.37 19:21:55 INFO - PROCESS | 1926 | Values: 106.0 118.0 119.0 140.0 171.0 157.0 118.0 119.0 118.0 118.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:115.00 stddev:14.23 (12.4%) stddev-sans-first:11.57 19:21:55 INFO - PROCESS | 1926 | Values: 146.0 109.0 108.0 138.0 112.0 116.0 112.0 109.0 136.0 114.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#2] audio-dft Cycles:10 Average:212.00 Median:200.00 stddev:27.64 (13.8%) stddev-sans-first:28.70 19:21:55 INFO - PROCESS | 1926 | Values: 196.0 198.0 195.0 201.0 197.0 199.0 223.0 275.0 245.0 191.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#3] audio-fft Cycles:10 Average:74.10 Median:76.00 stddev:14.26 (18.8%) stddev-sans-first:15.11 19:21:55 INFO - PROCESS | 1926 | Values: 76.0 72.0 81.0 76.0 83.0 50.0 56.0 66.0 100.0 81.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:9.87 (12.8%) stddev-sans-first:8.58 19:21:55 INFO - PROCESS | 1926 | Values: 92.0 69.0 87.0 70.0 69.0 70.0 84.0 68.0 65.0 85.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:90.00 stddev:17.95 (19.9%) stddev-sans-first:18.91 19:21:55 INFO - PROCESS | 1926 | Values: 91.0 89.0 91.0 87.0 92.0 80.0 130.0 131.0 92.0 88.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#6] imaging-darkroom Cycles:10 Average:110.50 Median:96.00 stddev:24.65 (25.7%) stddev-sans-first:21.09 19:21:55 INFO - PROCESS | 1926 | Values: 152.0 96.0 96.0 95.0 96.0 96.0 96.0 94.0 152.0 132.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:88.00 stddev:20.22 (23.0%) stddev-sans-first:21.26 19:21:55 INFO - PROCESS | 1926 | Values: 90.0 89.0 88.0 86.0 137.0 135.0 88.0 88.0 87.0 89.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.50 stddev:2.41 (4.1%) stddev-sans-first:2.35 19:21:55 INFO - PROCESS | 1926 | Values: 55.0 53.0 56.0 58.0 59.0 61.0 60.0 58.0 59.0 58.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:72.50 stddev:10.09 (13.9%) stddev-sans-first:10.12 19:21:55 INFO - PROCESS | 1926 | Values: 67.0 76.0 71.0 82.0 79.0 74.0 71.0 71.0 70.0 102.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:3.27 (3.9%) stddev-sans-first:3.41 19:21:55 INFO - PROCESS | 1926 | Values: 86.0 83.0 82.0 84.0 83.0 89.0 80.0 85.0 81.0 90.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:146.50 stddev:13.07 (8.9%) stddev-sans-first:13.76 19:21:55 INFO - PROCESS | 1926 | Values: 153.0 158.0 152.0 140.0 140.0 141.0 141.0 138.0 180.0 141.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:168.50 stddev:14.30 (8.5%) stddev-sans-first:14.73 19:21:55 INFO - PROCESS | 1926 | Values: 162.0 169.0 163.0 164.0 165.0 168.0 171.0 162.0 186.0 207.0 19:21:55 INFO - PROCESS | 1926 | 19:21:55 INFO - PROCESS | 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.85 (1.2%) stddev-sans-first:0.71 19:21:55 INFO - PROCESS | 1926 | Values: 69.0 67.0 68.0 67.0 67.0 67.0 68.0 68.0 66.0 68.0 19:21:55 INFO - PROCESS | 1926 | -------- Summary: end -------- 19:21:55 INFO - PROCESS | 1926 | 19:21:56 INFO - TEST-INFO | 1926: exit 0 19:21:56 INFO - TEST-OK | kraken | took 60081ms 19:21:56 INFO - SUITE-END | took 1174s 19:21:56 INFO - Completed test suite (00:19:35) 19:21:56 INFO - javascript benchmark 19:21:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7346.282646659095, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3581.8925432237043, 3592.1738000259943, 3611.295797319524, 3615.9819200904, 3642.690371318438, 5959.6424214547105, 5972.970135149329, 6007.339192688801, 6022.066579680146, 6050.909236361458, 5985.551083668721, 6063.696815159246, 6076.513878889689, 6079.848003799896, 6096.908546371808, 5375.462453754632, 5419.160030195326, 5419.430959749209, 5432.6468779529205, 5515.972420137894, 5454.400015998237, 5486.972565137169, 5487.0, 5487.917681234785, 5515.586331025169, 5331.360236771593, 5338.252644629755, 5390.434004429537, 5477.04151773438, 5488.067028605146, 5380.596455265871, 5416.052190866602, 5421.186821976696, 5426.538744206747, 5441.183822426668, 5203.713795741232, 5370.5435038021815, 5392.973035134799, 5399.541039011689, 5497.5601951843755, 5031.823886164003, 6113.23584551931, 6131.41751533608, 6143.569950103494, 6147.10866924298, 6165.660888651122, 6222.222222222223, 6291.370862913672, 6312.715927783261, 6354.777582784581, 6202.751889924398, 6253.593516421463, 6317.210348706411, 6321.936780632181, 6369.808905732789, 6076.088586711984, 6096.329403765582, 6102.938970610326, 6122.602030867979, 6128.662923539248, 6449.193850768654, 6556.147700798866, 6566.507511936624, 6588.571742836701, 6601.933980660228, 6155.96922015396, 6172.074188871705, 6229.0, 6252.624842509464, 6289.622622642656, 6413.679316034273, 6419.197600299963, 6429.0, 6435.74257029723, 6439.066335381437, 6179.351168127282, 6292.716827742763, 6300.464460520815, 6306.306306306303, 6317.147185129948, 5065.138926382524, 6196.659183744938, 6207.937920620826, 6219.502439804804, 6461.709223084972, 4282.743035417822, 4351.955530672616, 4556.270996640522, 5092.719900405439, 5095.949040509621, 4579.5878370946775, 4608.815647374135, 4615.792289346988, 4636.420447444069, 4645.6980296280635, 4620.792064357112, 6022.45797878193, 6025.6685882276015, 6128.693565321716, 6150.0, 6409.262934762562, 6430.228372595318, 6435.388638079375, 6445.548811583238, 6458.741650334028, 6004.369541198112, 6196.411340922605, 6220.284667263232, 6225.28409232944, 6234.625922444668, 5497.285352904097, 5534.920690465401, 5551.861203469864, 5590.133529302964, 5599.524040456526, 6575.243846957565, 6582.703778330082, 6587.374199451045, 6589.901151482636, 6599.703013364314, 6425.389587989133, 6425.5502114851515, 6433.099366088845, 6447.258565265054, 6449.838754031187, 6322.336154703874, 6417.6470294132405, 6503.869922601616, 6524.543281970216, 6626.801195964129, 6312.084747711464, 6336.20797400325, 6339.270983836734, 6345.460635846005, 6363.3000369958345, 6407.295197528175, 6407.583507072025, 6418.005209192583, 6431.421172094534, 6433.163688720437, 6142.867137759864, 6154.292256390393, 6214.564980451506, 6225.470834979077, 6275.905861411991, 6120.8775824484155, 6125.203723515914, 6177.135201071764, 6184.443400094013, 6244.687765611792, 5138.820141295037, 6134.110553969738, 6155.169052178077, 6234.096056071935, 6252.0, 6160.907586386118, 6169.043798211284, 6204.72078756448, 6216.160818289654, 6258.468030217483, 6126.761967422209, 6136.85608443425, 6215.81352559424, 6225.937740622717, 6244.188255526752], "value": 5873.104802424188, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.97276163430195, 490.0272958178017, 512.9128048231801, 520.8801975545624, 524.3131497737961, 40155.799221003894, 41744.165116697644, 41779.0, 43431.78284108583, 43582.34626480597, 42466.36300455496, 43021.56978430215, 43486.13027739443, 43560.78219608906, 43652.345214821806, 9635.084666956645, 9636.61453541861, 9770.267229957746, 9855.507224638775, 9867.555959981783, 9691.612335506572, 9715.70852874415, 9785.412875227508, 9795.706128816148, 9834.754131146743, 9696.981816909194, 9707.223422126213, 9797.363171393872, 9833.16418104462, 9851.261155413336, 9677.225821934227, 9762.974887636803, 9801.705948821547, 9802.0, 9846.803063938682, 9596.136347728669, 9660.468674222915, 9728.90271097287, 9770.462624555646, 9801.0, 40213.798931005156, 41072.178556429004, 41085.76742697724, 41194.38208426876, 41657.541986030345, 3277.524758909945, 3394.8811791587423, 3443.707284880788, 3453.0, 3834.271488417192, 27762.722372776214, 30413.783448662027, 30767.230819229473, 31103.22241943947, 31558.842205789053, 3218.097181545595, 3289.588801399825, 3333.300006998509, 3388.8983330500123, 3415.129142068781, 387336.0, 388791.99999999715, 389810.0, 389910.0, 390773.0, 59350.70324648349, 59554.0, 59659.403405965386, 59837.40162598405, 59849.70075149596, 59525.40474595285, 60707.0, 61064.0, 61572.0, 61957.0, 14042.789358159636, 16872.493825185265, 16886.98678079319, 16927.153642317833, 16961.830381696273, 13088.607341779762, 13237.0, 13270.071095023255, 13293.800592991112, 13304.800427993394, 13630.863691363158, 14578.63553411152, 15643.530694078969, 16227.918860405622, 16317.347306107622, 6883.7590684325805, 6958.721651133898, 7121.572705637678, 7135.500514963903, 7166.390856777232, 798.4211446701212, 997.7505623594102, 1008.1430783833829, 1036.4196050211906, 1079.2984560036039, 30208.54687179694, 30401.391972160432, 30852.382952340828, 31113.844430777703, 31146.532802007987, 1055.7096798380353, 1109.0573012938937, 1122.0, 1128.7911736328806, 1152.8040233160382, 15330.926835121434, 15837.841621583868, 15929.761053584205, 15946.362145514282, 16274.023558586523, 694398.0, 694585.0, 694665.0, 696278.0, 700973.0, 38883.0, 41594.0, 44417.33373999393, 44449.0, 44493.55506444894, 1397.958061258164, 1402.4810819996667, 1427.2863568215892, 1430.9642258943609, 1433.2690327933037, 25034.74965250324, 29694.257643559085, 30236.24409389783, 30333.848330759087, 30448.543271851384, 50481.74759126181, 50789.0, 50846.49153508418, 50875.0, 51113.744431277606, 631729.0, 635195.0, 636705.0, 638708.0, 639522.0, 26618.467630647665, 26993.865030674722, 27213.591796122677, 27532.311692207855, 27604.723952760214, 46918.76540617275, 47453.76273118612, 47879.76060119677, 48531.757341213066, 48846.0, 38839.14111591076, 39080.41379379368, 39251.214975700896, 40162.79918600388, 40657.780266592046, 38836.41745373765, 39208.411873822486, 39522.0, 40054.59808906674, 40419.98950026273, 37995.050123747125, 38993.41509877411, 39232.80383598064, 39282.607173929035, 39307.82076537709, 34002.659973400936, 34277.31445371128, 35321.293574127856, 35434.29131417409, 35964.0, 20207.191712331343, 20343.69484457764, 21899.0, 25978.480430391664, 26030.0], "value": 19849.336346399337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.6165872468807, 385.9445303192905, 388.64633183802744, 389.98050097495127, 414.9750117210491, 19700.310489132884, 20381.388558343275, 20942.26702065428, 21119.788802111972, 21179.68230476542, 20756.584868302663, 20959.58080838386, 21042.263520776774, 21109.788902110973, 21193.470163245926, 5627.296587926509, 5959.255093113361, 5995.850103747398, 6029.306629737571, 6107.175531303262, 5926.792562260322, 5952.70236488176, 6069.848253793646, 6090.3909609038965, 6164.383561643845, 5297.205419187114, 5848.766049358021, 5910.024845900442, 6070.210872586557, 6071.544634152434, 5805.36141024487, 5956.880862382728, 5994.310654274749, 6052.122442245894, 6055.152278680988, 5159.793608255665, 5159.845204643829, 5254.579633629339, 5279.841604751825, 5317.8404647860625, 25821.61267580969, 25950.0, 25966.610500842504, 26066.0, 26770.063047793235, 2666.2401215653445, 2786.9442611147865, 2818.957715634267, 3210.1011716719354, 3227.596550431196, 15175.62060948474, 16011.91994040034, 16161.03033817963, 16174.919125404298, 16676.16619169037, 1910.484169274302, 1971.5465442948203, 1984.7958275534527, 2012.11466954541, 2018.3743039657606, 115791.0, 119999.0, 120723.0, 120772.1305082538, 134133.0, 9298.53507324631, 9643.517824108767, 11738.708742038369, 11869.0, 11922.821157682642, 14826.184559849096, 15201.0, 15203.847961520243, 15219.162946037854, 15222.010569312959, 8701.086385929386, 8762.561871906506, 8846.115388461103, 8880.422772519765, 8977.192052715156, 7098.3256590623805, 8749.518776467356, 8960.372773905765, 8966.193042626195, 8979.640814367353, 4635.7218566886095, 4652.13935421948, 4667.463241727177, 4670.929936050962, 4695.2252878275385, 1982.5440148765865, 2011.2055737983414, 2011.919523219084, 2012.577358754678, 2014.7380840490641, 858.7209157023992, 881.1100788203833, 883.960221790021, 967.4292167621137, 985.8077674853478, 21100.05049772764, 21855.344339669835, 21903.561928761337, 21934.341969740934, 21960.34118976433, 810.9107998120202, 918.1231923513029, 972.2416515118077, 978.4667356290838, 984.975375615601, 8374.832503349899, 11455.140864435201, 11595.0, 11624.94187529057, 11637.0, 602959.0, 611151.0, 611612.0, 616031.0, 619108.0, 102939.0, 123409.76590234162, 133277.33361333326, 134209.0, 134734.0, 960.6109525642127, 961.8749562556962, 989.6338354808767, 990.6235630460379, 996.5465713100522, 19045.0, 19392.612147756685, 19535.0, 19547.8045219546, 19744.0, 46524.0, 53542.46457535375, 56054.0, 56416.717916410154, 56444.43555564556, 60405.39594604173, 80598.0, 80938.19061809307, 83808.0, 84588.0, 6803.829904252433, 7947.84104317922, 8146.185381461806, 8229.506229626242, 8234.505929644241, 10639.787204256027, 12083.879161208275, 12267.325297108744, 12388.132830701765, 12501.187422817487, 10011.899881001096, 10540.262181647211, 10647.30792498485, 10775.730606734895, 10964.396958167377, 8018.879716804136, 8677.915260592426, 8885.80041694363, 9868.0, 10497.79004419892, 8671.566421678765, 9522.666706665234, 10596.993285637853, 10985.505652245509, 11261.155413344195, 6491.383318584727, 8003.159668234752, 8057.113717491188, 8079.515229086273, 8093.716719914774, 9890.357126786736, 10809.35143891369, 10900.563977440814, 10989.835152472559, 11188.496517656888], "value": 9735.434726286529, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.7666061760428, 1548.5276990517893, 1620.5300462865762, 1825.8356747892694, 1847.2333981397721, 6911.2743161967965, 7601.505902116359, 7857.21427857214, 8075.232852878982, 8722.127787221283, 8308.667653293862, 8579.270761985239, 8595.097514760939, 8707.825843483126, 8718.17177368149, 2366.337425520857, 2379.8334116611845, 2402.4474370894704, 2426.915057972971, 2440.7437219092008, 2425.9514809703796, 2428.781409673129, 2447.094575007241, 2464.125235541384, 2468.0, 2384.511175209078, 2392.605220138675, 2407.181558270188, 2420.8668523231217, 2439.8048156147556, 2344.542814151241, 2370.3481542575755, 2375.3705268103968, 2382.7617238276207, 2390.665306857024, 2402.0992127952018, 2439.902403903842, 2441.4872876695913, 2453.4234454903085, 2455.877206139704, 8609.096044915319, 8609.182127697859, 8610.182032706884, 8670.913290867074, 8776.517291548962, 5256.421793602701, 5331.306930099101, 5334.786608535693, 5413.675179489243, 5440.238366628675, 3544.344296305166, 3598.5501812273465, 3627.292677927805, 3633.1280492681567, 3663.1757854482794, 1074.2426589254558, 1123.6404350607809, 1144.3705961721103, 1147.9196456248067, 1674.8911320764232, 11137.554497820158, 11235.04502117321, 11368.317900925971, 11424.657260282205, 11437.828432573519, 4394.780260986938, 4594.839180628662, 4601.309803529497, 4641.628669706415, 4659.953400465952, 7980.561069141137, 8929.285657147542, 8967.372283940193, 8980.775480612907, 9000.414973026733, 5178.6374953752875, 5216.7913283469, 5226.869328266747, 5245.501677340647, 5248.475152484721, 4139.606737359946, 4434.445694288214, 4450.443694538183, 4938.678985865908, 4951.257311403247, 3144.9528257076163, 3864.5362556492837, 3883.3398322284716, 3892.3188442022533, 3953.2093581283857, 990.4898977026837, 1199.7900367435664, 1200.591798788416, 1202.2305724336431, 1202.272625061825, 866.7963028688254, 879.8020445399734, 886.3972498700816, 895.4403497813867, 898.8112496375834, 5804.912926306109, 5805.238801454326, 5876.353601103875, 5906.49794767441, 6059.091136329498, 946.8059047895165, 951.2960409297209, 963.643451922793, 967.7048500207363, 998.565623953568, 3621.402468592653, 3741.0955782548754, 3770.811459427018, 3788.0151160698415, 3795.899189235146, 10184.0, 10486.161107111413, 10541.525631346607, 10557.577696892038, 10557.9472102639, 10260.948695256626, 10469.214808889365, 10470.633527826489, 10492.265541412027, 10506.0, 7931.603419828985, 8050.154733752989, 8075.232852878967, 8205.974253218466, 8219.712310069119, 4273.124009578091, 4721.537605342705, 5059.114654935298, 5443.142236203582, 5598.188262701804, 9336.719898403058, 9343.579538920902, 9548.0, 9632.27757918173, 9637.37357071788, 8026.317762990211, 8976.833011708437, 9780.070893265129, 9907.157891579009, 9945.353552019096, 6846.863062738617, 7031.472639551952, 7164.749233776793, 7281.854362912818, 7446.292602202782, 7921.128675845767, 8053.798655033671, 8057.315128214167, 8226.259636632733, 8235.95882020586, 7235.782926512213, 7345.632718363953, 7395.667194976347, 7468.477206595703, 7499.925000749922, 7132.179799322937, 7228.602426866572, 7241.058662373857, 7242.746503872339, 7270.200277969525, 6708.362705542965, 6716.496262780409, 6808.489363297674, 6816.693248803915, 6832.316768323128, 4691.577758001797, 4759.50025247344, 4776.546228108324, 4812.494688057704, 4870.439899411613, 3937.31097058008, 4612.123696497655, 4661.463931647904, 4794.856154315376, 4867.245576935581], "value": 4491.462377359294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.57262691172735, 311.7350252285557, 318.9888353907612, 320.7722517012921, 321.21463022908966, 4519.0, 4660.9512859606575, 4974.57716094132, 5368.698250575897, 5558.749856256468, 4291.957080429194, 4651.743904206457, 4862.440819305781, 5350.876315973641, 5437.0, 4091.1204091120403, 4183.4770653668365, 4237.173751118533, 4238.0, 4263.507772279693, 4162.854300099497, 4277.572242775713, 4288.399624052635, 4292.356146578007, 4315.590018948211, 3669.3395188865993, 4174.996001919066, 4207.326827707568, 4211.494620645527, 4269.6370808481315, 3888.747231429948, 3995.9200815983663, 4224.767637779921, 4228.830846766125, 4242.596953289463, 3695.63043695631, 3696.9630303696886, 3715.2383761329133, 3724.4971928789537, 3752.699784017272, 4705.835295764632, 5168.0, 5311.415744268127, 5406.864828379283, 5483.369412517572, 4888.01830883161, 4896.135760580065, 4930.654854160211, 4932.8273510427325, 4940.419065898878, 5620.634658747168, 5887.8233652990475, 5900.262467191601, 5908.586398952075, 5948.494377977877, 3518.5074089627474, 3800.011996880803, 4042.4946881639794, 4086.4687590613325, 4097.9100658664, 5051.545360917498, 5882.47057764799, 6107.236595425572, 6125.418085281891, 6157.7229024694, 3486.9825650871585, 3653.8355773990234, 4142.316517774533, 4156.147989662112, 4170.249355116048, 4034.5677284564144, 4142.399352093989, 4200.684948628804, 4202.70581059329, 4223.184030866695, 4870.0, 4888.120138375056, 4907.214845624683, 4909.165441874889, 4915.68048076881, 5718.79984200551, 5904.61619994699, 5918.8816223675285, 5948.375420580864, 6004.118023348817, 1147.7718840840387, 1176.4588289386807, 1198.0295960272033, 1377.7726675098697, 1377.9931100344434, 747.8990336304638, 820.9630566624517, 906.3066753933247, 907.8229745199623, 927.4991504587447, 4406.984466203673, 4628.038055644391, 4645.512221216791, 5383.327084114486, 5406.189071639207, 4923.852284431473, 5529.336479622471, 5893.941060589339, 5896.204012458349, 5896.3219229788265, 4697.9429628333355, 5013.724245166551, 5312.015988008993, 5339.519443250126, 5396.541293990055, 2787.5539913613725, 2818.6758522769733, 2976.4968690811, 3085.7222849943614, 3099.4038070393767, 5293.570735901285, 5998.520118390518, 6040.607360521552, 6072.514198864169, 6139.508839292846, 5826.727576245267, 5968.671723055274, 6309.463695585927, 6358.491320694333, 6373.012183111625, 5308.858595402007, 5403.351597808288, 6284.874302514016, 6435.356464353621, 6471.741130354827, 5960.781973433598, 6045.697715114139, 6119.969400152971, 6201.472874805511, 6240.0, 6062.939370606414, 6090.817275481742, 6096.695165241631, 6105.542084343603, 6121.84695382619, 4895.975520122377, 5336.332958380202, 5698.1737648041635, 6353.491720662243, 6377.333033505845, 3814.713896457721, 3929.3713005918917, 3994.920101597894, 4109.671226301829, 4119.938200926928, 6145.432914606926, 6150.815475535741, 6151.523634327639, 6164.0, 6176.598521096114, 4934.383202099738, 5002.874928126826, 5481.917771233514, 5607.43925607436, 5938.61399009063, 4891.877703057452, 5153.665011774223, 5477.20580515815, 5918.348981611933, 5933.2880054393745, 5058.570021548209, 5474.425185355481, 5787.508061814793, 5831.413680586324, 5859.707014649165, 4938.777755000961, 5746.569007324551, 5859.707014649336, 5942.702864856827, 5952.464278214981, 5726.564061563029, 5788.623739456922, 5815.883682326415, 5834.795782147605, 5844.47399734026], "value": 4246.319440399269, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.381047772721, 768.9000429944107, 777.4907435629661, 791.6318911706055, 814.2061490047201, 15000.249987500622, 15064.171470569141, 15396.846031539695, 15709.057456552615, 15736.0, 13827.170369777821, 13829.515966941159, 13984.580462586113, 14034.929825350859, 14241.71516569668, 838.6477679374677, 855.2388374346821, 868.288003836854, 869.0961400143843, 871.8212766382908, 838.4298676899706, 852.0244320253306, 854.0605334132435, 855.7389996051202, 856.8414843253987, 892.3040028777564, 900.4102312985005, 904.1275169461468, 910.9726708198732, 913.9854761215079, 896.9551522423918, 897.3045889435707, 897.6409436225497, 898.137787723786, 900.3787386703186, 666.1373521289893, 669.7823207457595, 678.4402867634172, 681.0567364200568, 684.3806355248508, 12495.312757798318, 12802.551910683174, 12821.743565128738, 12894.290814005226, 13117.934410327887, 9634.71095867125, 11416.915393037649, 12112.515499380013, 12123.939380303042, 12215.052481508548, 9482.905170948272, 10684.893151068545, 10916.617918372898, 10974.890251097546, 11067.335959842436, 3655.2872189922978, 3684.5578530576504, 3705.6479634434686, 3753.530808648919, 3845.5193100862393, 86129.13870861274, 89221.0, 94047.05952940452, 94481.0, 96009.0, 8656.350773691998, 8969.686060987835, 9168.862467063, 9213.723588292361, 9483.14651681352, 18629.44111676652, 18912.0, 19075.42773716763, 19211.423657290023, 19225.51936201606, 7817.296443320015, 7866.9213307866185, 7964.8805267921025, 8020.2781749641645, 8101.513909165469, 4401.933970990438, 4431.889202769892, 4443.355713421601, 4521.547845215518, 4539.114872599877, 3391.9491207631904, 3501.77238479498, 3507.859685612598, 3543.645635436436, 3546.0957455848593, 634.6572850660684, 638.4254171245916, 644.4103645164715, 648.597869321024, 649.4804156674642, 615.5875563372551, 621.8227805075622, 660.7687309441657, 673.4275865514273, 687.8693048320803, 8284.005919289602, 8325.666973321, 8954.955225223832, 9069.59186836594, 9160.6793762218, 543.6466296906963, 559.5965328912331, 622.9503286961484, 664.3356643356643, 683.8016975077271, 4472.262076757299, 4633.2818413146015, 4645.976770116128, 4673.0920027395305, 4809.2305231162845, 76132.23867761252, 99190.00809991952, 101397.49301253447, 108806.0, 109375.81248375148, 62899.0, 63446.0483092754, 77277.0, 87850.56074719585, 102623.0, 46880.5311946883, 70687.2931270691, 77551.22448775553, 83082.58458707668, 84749.0, 9370.484623345781, 9868.654597089067, 9870.407775533491, 9886.0, 9912.553935073083, 43844.56155438533, 46901.0, 54799.45200548103, 56116.75532978635, 56898.0, 74991.62504187445, 80140.0, 80796.59601701953, 83186.58406707928, 84220.0, 12395.938020310201, 12486.625401237978, 12564.0, 12661.810072849099, 12786.808197876853, 13851.0, 14424.711505770036, 14455.927720361751, 14532.122488722967, 14725.190114543802, 13077.280749558866, 13607.86392136066, 13626.727465450835, 13915.652108697364, 14075.155490670593, 11293.040091592307, 13239.602811915658, 13419.530316439268, 13587.954825645138, 14165.575032749033, 12609.0, 12736.74526509483, 13034.543790967271, 13192.076554641082, 13369.570637809493, 10769.784604308026, 10806.729831754268, 11267.380294083903, 11308.151888608223, 11528.423578820857, 10899.509522071367, 11046.558137674725, 11205.775884482102, 11316.490757916079, 11357.432128393382], "value": 7260.922206729989, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 119.0, 140.0, 171.0, 157.0, 118.0, 119.0, 118.0, 118.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 108.0, 138.0, 112.0, 116.0, 112.0, 109.0, 136.0, 114.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 195.0, 201.0, 197.0, 199.0, 223.0, 275.0, 245.0, 191.0], "value": 212.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 81.0, 76.0, 83.0, 50.0, 56.0, 66.0, 100.0, 81.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 87.0, 70.0, 69.0, 70.0, 84.0, 68.0, 65.0, 85.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 87.0, 92.0, 80.0, 130.0, 131.0, 92.0, 88.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 94.0, 152.0, 132.0], "value": 110.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 88.0, 86.0, 137.0, 135.0, 88.0, 88.0, 87.0, 89.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 58.0, 59.0, 61.0, 60.0, 58.0, 59.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 71.0, 82.0, 79.0, 74.0, 71.0, 71.0, 70.0, 102.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 82.0, 84.0, 83.0, 89.0, 80.0, 85.0, 81.0, 90.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 158.0, 152.0, 140.0, 140.0, 141.0, 141.0, 138.0, 180.0, 141.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 163.0, 164.0, 165.0, 168.0, 171.0, 162.0, 186.0, 207.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 68.0, 66.0, 68.0], "value": 67.5, "unit": "score"}]}]} 19:21:57 INFO - Return code: 0 19:21:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:21:57 ERROR - # TBPL SUCCESS # 19:21:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:21:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:21:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:21:57 INFO - 2017-02-02 19:21:57.173 screenresolution[1931:17149] starting screenresolution argv=screenresolution get 19:21:57 INFO - 2017-02-02 19:21:57.177 screenresolution[1931:17149] Display 0: 1600x1200x32@60 19:21:57 INFO - 2017-02-02 19:21:57.183 screenresolution[1932:17152] starting screenresolution argv=screenresolution list 19:21:57 INFO - Available Modes on Display 0 19:21:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:21:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:21:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:21:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:21:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:21:57 INFO - Intel Iris: 19:21:57 INFO - Chipset Model: Intel Iris 19:21:57 INFO - Type: GPU 19:21:57 INFO - Bus: Built-In 19:21:57 INFO - VRAM (Dynamic, Max): 1536 MB 19:21:57 INFO - Vendor: Intel (0x8086) 19:21:57 INFO - Device ID: 0x0a2e 19:21:57 INFO - Revision ID: 0x0009 19:21:57 INFO - Displays: 19:21:57 INFO - Display: 19:21:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:21:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:21:57 INFO - Display Serial Number: 1600x1200 60 19:21:57 INFO - Main Display: Yes 19:21:57 INFO - Mirror: Off 19:21:57 INFO - Online: Yes 19:21:57 INFO - Rotation: Supported 19:21:57 INFO - Return code: 0 19:21:57 INFO - Running post-action listener: _resource_record_post_action 19:21:57 INFO - [mozharness: 2017-02-03 03:21:57.431262Z] Finished run-tests step (success) 19:21:57 INFO - Running post-run listener: _resource_record_post_run 19:21:57 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 2061312; Write bytes: 502961152; Read time: 21; Write time: 3252 19:21:57 INFO - TinderboxPrint: CPU usage
54.3% 19:21:57 INFO - TinderboxPrint: I/O read bytes / time
2,061,312 / 21 19:21:57 INFO - TinderboxPrint: I/O write bytes / time
502,961,152 / 3,252 19:21:57 INFO - TinderboxPrint: CPU idle
2,190.1 (45.7%) 19:21:57 INFO - TinderboxPrint: CPU system
973.3 (20.3%) 19:21:57 INFO - TinderboxPrint: CPU user
1,626.4 (34.0%) 19:21:57 INFO - TinderboxPrint: Swap in / out
193,630,208 / 0 19:21:57 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 178795008; Write bytes: 177251840; Read time: 16594; Write time: 386 19:21:57 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 2061312; Write bytes: 307977728; Read time: 21; Write time: 2813 19:21:57 INFO - Running post-run listener: _upload_blobber_files 19:21:57 INFO - Blob upload gear active. 19:21:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:21:57 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 19:21:57 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:21:57 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:21:58 INFO - (blobuploader) - INFO - Open directory for files ... 19:21:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:21:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:21:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:59 INFO - (blobuploader) - INFO - Done attempting. 19:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:00 INFO - (blobuploader) - INFO - Done attempting. 19:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:22:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:02 INFO - (blobuploader) - INFO - Done attempting. 19:22:02 INFO - (blobuploader) - INFO - Iteration through files over. 19:22:02 INFO - Return code: 0 19:22:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:22:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:22:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2f8c96ca3d59add307c14f4e6f06317b1b5fc1a69e818ed113db9ea93dcd772fbd11114c88ce48634d8345996340082ee5a2e1835eda35bca316ccf13283879b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00179a03c1f740b8122173925be84891a7f9d1d59721e42b94f994e6d4b7f5179c292b187dc4d4426b42e532bf4df64271403cda66d05ccc5ffbfae6da91cbc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff5c88eabeeaa2eed6eb7696501ea34a3bee2dbd12fe38fbe1bd47b897160f8adfba7c2cc8c77c708c203a54d3d17f1732ff3a47488e20a695860785de49147c"} 19:22:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:22:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:22:02 INFO - Contents: 19:22:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2f8c96ca3d59add307c14f4e6f06317b1b5fc1a69e818ed113db9ea93dcd772fbd11114c88ce48634d8345996340082ee5a2e1835eda35bca316ccf13283879b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00179a03c1f740b8122173925be84891a7f9d1d59721e42b94f994e6d4b7f5179c292b187dc4d4426b42e532bf4df64271403cda66d05ccc5ffbfae6da91cbc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff5c88eabeeaa2eed6eb7696501ea34a3bee2dbd12fe38fbe1bd47b897160f8adfba7c2cc8c77c708c203a54d3d17f1732ff3a47488e20a695860785de49147c"} 19:22:02 INFO - Running post-run listener: copy_logs_to_upload_dir 19:22:02 INFO - Copying logs to upload dir... 19:22:02 INFO - mkdir: /builds/slave/test/build/upload/logs 19:22:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.640119 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-02 19:22:02.346925) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 19:22:02.350678) ========= 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.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-Beta/sha512/2f8c96ca3d59add307c14f4e6f06317b1b5fc1a69e818ed113db9ea93dcd772fbd11114c88ce48634d8345996340082ee5a2e1835eda35bca316ccf13283879b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00179a03c1f740b8122173925be84891a7f9d1d59721e42b94f994e6d4b7f5179c292b187dc4d4426b42e532bf4df64271403cda66d05ccc5ffbfae6da91cbc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff5c88eabeeaa2eed6eb7696501ea34a3bee2dbd12fe38fbe1bd47b897160f8adfba7c2cc8c77c708c203a54d3d17f1732ff3a47488e20a695860785de49147c"} build_url:https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011059 build_url: 'https://queue.taskcluster.net/v1/task/VdLi2f6DRtaxT2uhHV70uw/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2f8c96ca3d59add307c14f4e6f06317b1b5fc1a69e818ed113db9ea93dcd772fbd11114c88ce48634d8345996340082ee5a2e1835eda35bca316ccf13283879b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00179a03c1f740b8122173925be84891a7f9d1d59721e42b94f994e6d4b7f5179c292b187dc4d4426b42e532bf4df64271403cda66d05ccc5ffbfae6da91cbc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff5c88eabeeaa2eed6eb7696501ea34a3bee2dbd12fe38fbe1bd47b897160f8adfba7c2cc8c77c708c203a54d3d17f1732ff3a47488e20a695860785de49147c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 19:22:02.384035) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:22:02.384328) ========= 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.lZDve1Fg2h/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.cypyd4GcrB/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 19:22:02.431694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 19:22:02.431967) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 19:22:02.432238) ========= ========= Total master_lag: 1.78 =========