builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0017 starttime: 1479846940.66 results: success (0) buildid: 20161122075551 builduid: f47f47b22a3b41898a077fa6c8129ed1 revision: 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.661639) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.662550) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.663176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.689832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.690210) ========= 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.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 --2016-11-22 12:35:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|: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.22M=0.002s 2016-11-22 12:35:40 (7.22 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142011 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.858828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.859233) ========= 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.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.020813 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:40.911647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-22 12:35:40.912487) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 2016-11-22 12:35:40,988 truncating revision to first 12 chars 2016-11-22 12:35:40,988 Setting DEBUG logging. 2016-11-22 12:35:40,988 attempt 1/10 2016-11-22 12:35:40,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/513016d8b5ce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 12:35:42,800 unpacking tar archive at: cedar-513016d8b5ce/testing/mozharness/ program finished with exit code 0 elapsedTime=2.058607 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-22 12:35:43.000015) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:43.000381) ========= script_repo_revision: 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:43.000814) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:43.001138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 12:35:43.022059) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-22 12:35:43.022693) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:35:43 INFO - MultiFileLogger online at 20161122 12:35:43 in /builds/slave/test 12:35:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 12:35:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:35:43 INFO - {'append_to_log': False, 12:35:43 INFO - 'base_work_dir': '/builds/slave/test', 12:35:43 INFO - 'blob_upload_branch': 'Cedar', 12:35:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:35:43 INFO - 'branch': 'Cedar', 12:35:43 INFO - 'buildbot_json_path': 'buildprops.json', 12:35:43 INFO - 'config_files': ('talos/mac_config.py',), 12:35:43 INFO - 'default_actions': ('clobber', 12:35:43 INFO - 'read-buildbot-config', 12:35:43 INFO - 'download-and-extract', 12:35:43 INFO - 'populate-webroot', 12:35:43 INFO - 'create-virtualenv', 12:35:43 INFO - 'install', 12:35:43 INFO - 'run-tests'), 12:35:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:35:43 INFO - 'download_minidump_stackwalk': True, 12:35:43 INFO - 'download_symbols': 'ondemand', 12:35:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:35:43 INFO - 'tooltool.py': '/tools/tooltool.py', 12:35:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:35:43 INFO - '/tools/misc-python/virtualenv.py')}, 12:35:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:35:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:35:43 INFO - 'installer_path': 'installer.exe', 12:35:43 INFO - 'log_level': 'info', 12:35:43 INFO - 'log_name': 'talos', 12:35:43 INFO - 'log_to_console': True, 12:35:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:35:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:35:43 INFO - 'opt_config_files': (), 12:35:43 INFO - 'pip_index': False, 12:35:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:35:43 INFO - 'cmd': ('bash', 12:35:43 INFO - '-c', 12:35:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:35:43 INFO - 'enabled': True, 12:35:43 INFO - 'halt_on_failure': False, 12:35:43 INFO - 'name': 'check_screen_resolution'},), 12:35:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:35:43 INFO - 'cmd': ('bash', 12:35:43 INFO - '-c', 12:35:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:35:43 INFO - 'enabled': True, 12:35:43 INFO - 'halt_on_failure': False, 12:35:43 INFO - 'name': 'check_screen_resolution'},), 12:35:43 INFO - 'run_cmd_checks_enabled': True, 12:35:43 INFO - 'sps_profile': False, 12:35:43 INFO - 'sps_profile_interval': 0, 12:35:43 INFO - 'suite': 'dromaeojs-e10s', 12:35:43 INFO - 'system_bits': '32', 12:35:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:35:43 INFO - 'title': 't-yosemite-r7-0017', 12:35:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:35:43 INFO - 'use_talos_json': True, 12:35:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:35:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:35:43 INFO - 'work_dir': 'build'} 12:35:43 INFO - [mozharness: 2016-11-22 20:35:43.225079Z] Running clobber step. 12:35:43 INFO - Running pre-action listener: _resource_record_pre_action 12:35:43 INFO - Running main action method: clobber 12:35:43 INFO - rmtree: /builds/slave/test/build 12:35:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:35:44 INFO - Running post-action listener: _resource_record_post_action 12:35:44 INFO - [mozharness: 2016-11-22 20:35:44.872922Z] Finished clobber step (success) 12:35:44 INFO - [mozharness: 2016-11-22 20:35:44.873050Z] Running read-buildbot-config step. 12:35:44 INFO - Running pre-action listener: _resource_record_pre_action 12:35:44 INFO - Running main action method: read_buildbot_config 12:35:44 INFO - Using buildbot properties: 12:35:44 INFO - { 12:35:44 INFO - "project": "", 12:35:44 INFO - "product": "firefox", 12:35:44 INFO - "script_repo_revision": "default", 12:35:44 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 12:35:44 INFO - "repository": "", 12:35:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 12:35:44 INFO - "buildid": "20161122075551", 12:35:44 INFO - "pgo_build": "False", 12:35:44 INFO - "basedir": "/builds/slave/test", 12:35:44 INFO - "buildnumber": 0, 12:35:44 INFO - "slavename": "t-yosemite-r7-0017", 12:35:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:35:44 INFO - "platform": "yosemite_r7", 12:35:44 INFO - "scheduler": "tests-cedar-macosx64-talos", 12:35:44 INFO - "branch": "cedar", 12:35:44 INFO - "repo_path": "projects/cedar", 12:35:44 INFO - "revision": "513016d8b5ce2f4e3cc50cb83abd4c2dcf661325", 12:35:44 INFO - "stage_platform": "macosx64", 12:35:44 INFO - "builduid": "f47f47b22a3b41898a077fa6c8129ed1", 12:35:44 INFO - "slavebuilddir": "test" 12:35:44 INFO - } 12:35:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:35:44 INFO - Running post-action listener: _resource_record_post_action 12:35:44 INFO - [mozharness: 2016-11-22 20:35:44.876191Z] Finished read-buildbot-config step (success) 12:35:44 INFO - [mozharness: 2016-11-22 20:35:44.876308Z] Running download-and-extract step. 12:35:44 INFO - Running pre-action listener: _resource_record_pre_action 12:35:44 INFO - Running main action method: download_and_extract 12:35:44 INFO - mkdir: /builds/slave/test/build/tests 12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:44 INFO - trying https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:35:44 INFO - Downloading https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:35:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 12:35:45 INFO - Downloaded 1199 bytes. 12:35:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:35:45 INFO - Using the following test package requirements: 12:35:45 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:35:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:35:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:35:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'jsshell-mac.zip'], 12:35:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:35:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:35:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:35:45 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:35:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:35:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:35:45 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:35:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:35:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:35:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:35:45 INFO - Fetch https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:35:46 INFO - Http code: 200 12:35:46 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 12:35:46 INFO - x-amz-cf-id: i8Xqn7acirCFOEbKjB_IHWX0U3BzkC2hSDtg1aHYqIDRb5CdHDZGQA== 12:35:46 INFO - x-amz-version-id: fi4_ylA936VnHbJYFImzteg70DdBbZXz 12:35:46 INFO - Expected file size: 16248536 12:35:46 INFO - Obtained file size: 16248536 12:35:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:35:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:35:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:35:53 INFO - Fetch https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:35:53 INFO - Http code: 200 12:35:53 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 12:35:53 INFO - x-amz-cf-id: a6HAyZN8y_gY_YYGe0ggOoGAtRGv6dZs47e1eLYedMiD-LhU20YSrg== 12:35:53 INFO - x-amz-version-id: fi4_ylA936VnHbJYFImzteg70DdBbZXz 12:35:54 INFO - Expected file size: 16248536 12:35:54 INFO - Obtained file size: 16248536 12:36:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:36:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:36:00 INFO - Fetch https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:36:00 INFO - Http code: 200 12:36:00 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 12:36:00 INFO - x-amz-cf-id: J-DvJfzUtpka76XzOeokIgAPqLcJNaOzEu7dejEA_FKUS2nnLiv-wg== 12:36:00 INFO - x-amz-version-id: UXc8Kynch_EOFG8cwnZzTQwJxZJM4ta5 12:36:01 INFO - Expected file size: 14409392 12:36:01 INFO - Obtained file size: 14409392 12:36:01 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')]} 12:36:01 INFO - trying https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:36:01 INFO - Downloading https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 12:36:04 INFO - Downloaded 107448994 bytes. 12:36:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:36:04 INFO - mkdir: /builds/slave/test/properties 12:36:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:36:04 INFO - Writing to file /builds/slave/test/properties/build_url 12:36:04 INFO - Contents: 12:36:04 INFO - build_url:https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:36:04 INFO - Running post-action listener: _resource_record_post_action 12:36:04 INFO - Running post-action listener: set_extra_try_arguments 12:36:04 INFO - [mozharness: 2016-11-22 20:36:04.859377Z] Finished download-and-extract step (success) 12:36:04 INFO - [mozharness: 2016-11-22 20:36:04.859494Z] Running populate-webroot step. 12:36:04 INFO - Running pre-action listener: _resource_record_pre_action 12:36:04 INFO - Running main action method: populate_webroot 12:36:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:36:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:36:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:36:04 INFO - '--tppagecycles', 12:36:04 INFO - '7'], 12:36:04 INFO - 'tests': ['tsvgm']}}, 12:36:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:36:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:36:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:36:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:36:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['damp', 'tps']}, 12:36:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'tests': ['damp', 'tps']}, 12:36:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['dromaeo_dom']}, 12:36:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:36:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['basic_compositor_video']}, 12:36:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:36:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'other-e10s': {'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['a11yr', 12:36:04 INFO - 'ts_paint', 12:36:04 INFO - 'tpaint', 12:36:04 INFO - 'sessionrestore', 12:36:04 INFO - 'sessionrestore_no_auto_restore', 12:36:04 INFO - 'tabpaint']}, 12:36:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['tsvgx', 12:36:04 INFO - 'tsvgr_opacity', 12:36:04 INFO - 'tart', 12:36:04 INFO - 'tscrollx', 12:36:04 INFO - 'cart']}, 12:36:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:36:04 INFO - 'tsvgr_opacity', 12:36:04 INFO - 'tart', 12:36:04 INFO - 'tscrollx', 12:36:04 INFO - 'cart']}, 12:36:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'talos_options': ['--disable-e10s'], 12:36:04 INFO - 'tests': ['tp5o']}, 12:36:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:36:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'talos_options': ['--disable-e10s', 12:36:04 INFO - '--xperf_path', 12:36:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:04 INFO - 'tests': ['tp5n']}, 12:36:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:36:04 INFO - 'talos_options': ['--xperf_path', 12:36:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:04 INFO - 'tests': ['tp5n']}}, 12:36:04 INFO - 'talos.zip': {'path': '', 12:36:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:36:04 INFO - Running post-action listener: _resource_record_post_action 12:36:04 INFO - [mozharness: 2016-11-22 20:36:04.877433Z] Finished populate-webroot step (success) 12:36:04 INFO - [mozharness: 2016-11-22 20:36:04.877542Z] Running create-virtualenv step. 12:36:04 INFO - Running pre-action listener: _resource_record_pre_action 12:36:04 INFO - Running main action method: create_virtualenv 12:36:04 INFO - Creating virtualenv /builds/slave/test/build/venv 12:36:04 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 12:36:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:36:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:36:05 INFO - Using real prefix '/tools/python27' 12:36:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:36:05 INFO - Installing distribute.............................................................................................................................................................................................done. 12:36:08 INFO - Installing pip.................done. 12:36:08 INFO - Return code: 0 12:36:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:36:08 INFO - Reading from file tmpfile_stdout 12:36:08 INFO - Output received: 12:36:08 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) 12:36:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:36:08 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')]} 12:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:08 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')]} 12:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:08 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:08 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 12:36:08 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 12:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:08 INFO - 'HOME': '/Users/cltbld', 12:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:08 INFO - 'LOGNAME': 'cltbld', 12:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:08 INFO - 'MOZ_NO_REMOTE': '1', 12:36:08 INFO - 'NO_EM_RESTART': '1', 12:36:08 INFO - 'PAGER': '/bin/cat', 12:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:08 INFO - 'PWD': '/builds/slave/test', 12:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:08 INFO - 'SHELL': '/bin/bash', 12:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:08 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:08 INFO - 'USER': 'cltbld', 12:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:08 INFO - 'XPC_FLAGS': '0x0', 12:36:08 INFO - 'XPC_SERVICE_NAME': '0', 12:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:09 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 12:36:09 INFO - Cleaning up... 12:36:09 INFO - Return code: 0 12:36:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:36:09 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')]} 12:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:09 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')]} 12:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:09 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:09 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 12:36:09 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 12:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:09 INFO - 'HOME': '/Users/cltbld', 12:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:09 INFO - 'LOGNAME': 'cltbld', 12:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:09 INFO - 'MOZ_NO_REMOTE': '1', 12:36:09 INFO - 'NO_EM_RESTART': '1', 12:36:09 INFO - 'PAGER': '/bin/cat', 12:36:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:09 INFO - 'PWD': '/builds/slave/test', 12:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:09 INFO - 'SHELL': '/bin/bash', 12:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:09 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:09 INFO - 'USER': 'cltbld', 12:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:09 INFO - 'XPC_FLAGS': '0x0', 12:36:09 INFO - 'XPC_SERVICE_NAME': '0', 12:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:09 INFO - Downloading/unpacking psutil>=3.1.1 12:36:09 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 12:36:09 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 12:36:09 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 12:36:09 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 12:36:09 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 12:36:09 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 12:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:36:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:12 INFO - Installing collected packages: psutil 12:36:12 INFO - Running setup.py install for psutil 12:36:12 INFO - building 'psutil._psutil_osx' extension 12:36:12 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 12:36:13 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 12:36:13 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 12:36:13 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 12:36:13 INFO - building 'psutil._psutil_posix' extension 12:36:13 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 12:36:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:36:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:36:13 INFO - ^ 12:36:13 INFO - 1 warning generated. 12:36:13 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 12:36:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:13 INFO - Successfully installed psutil 12:36:13 INFO - Cleaning up... 12:36:13 INFO - Return code: 0 12:36:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:36:13 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')]} 12:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:13 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')]} 12:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:13 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:13 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 12:36:13 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 12:36:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:13 INFO - 'HOME': '/Users/cltbld', 12:36:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:13 INFO - 'LOGNAME': 'cltbld', 12:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:13 INFO - 'MOZ_NO_REMOTE': '1', 12:36:13 INFO - 'NO_EM_RESTART': '1', 12:36:13 INFO - 'PAGER': '/bin/cat', 12:36:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:13 INFO - 'PWD': '/builds/slave/test', 12:36:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:13 INFO - 'SHELL': '/bin/bash', 12:36:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:13 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:13 INFO - 'USER': 'cltbld', 12:36:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:13 INFO - 'XPC_FLAGS': '0x0', 12:36:13 INFO - 'XPC_SERVICE_NAME': '0', 12:36:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:36:13 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 12:36:13 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 12:36:13 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 12:36:13 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 12:36:13 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 12:36:13 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 12:36:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:36:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:36:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:36:16 INFO - Installing collected packages: mozsystemmonitor 12:36:16 INFO - Running setup.py install for mozsystemmonitor 12:36:16 INFO - Successfully installed mozsystemmonitor 12:36:16 INFO - Cleaning up... 12:36:16 INFO - Return code: 0 12:36:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:36:16 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')]} 12:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:16 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')]} 12:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:16 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:16 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 12:36:16 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 12:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:16 INFO - 'HOME': '/Users/cltbld', 12:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:16 INFO - 'LOGNAME': 'cltbld', 12:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:16 INFO - 'MOZ_NO_REMOTE': '1', 12:36:16 INFO - 'NO_EM_RESTART': '1', 12:36:16 INFO - 'PAGER': '/bin/cat', 12:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:16 INFO - 'PWD': '/builds/slave/test', 12:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:16 INFO - 'SHELL': '/bin/bash', 12:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:16 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:16 INFO - 'USER': 'cltbld', 12:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:16 INFO - 'XPC_FLAGS': '0x0', 12:36:16 INFO - 'XPC_SERVICE_NAME': '0', 12:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:17 INFO - Downloading/unpacking jsonschema==2.5.1 12:36:17 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 12:36:17 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 12:36:17 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 12:36:17 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 12:36:17 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 12:36:17 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 12:36:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:36:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:36:19 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 12:36:19 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 12:36:19 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 12:36:19 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 12:36:19 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 12:36:19 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 12:36:19 INFO - Downloading functools32-3.2.3-2.tar.gz 12:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:36:20 INFO - warning: no files found matching '*.txt' 12:36:20 INFO - no previously-included directories found matching 'build' 12:36:20 INFO - no previously-included directories found matching 'dist' 12:36:20 INFO - no previously-included directories found matching '.git*' 12:36:20 INFO - Installing collected packages: jsonschema, functools32 12:36:20 INFO - Running setup.py install for functools32 12:36:20 INFO - warning: no files found matching '*.txt' 12:36:20 INFO - no previously-included directories found matching 'build' 12:36:20 INFO - no previously-included directories found matching 'dist' 12:36:20 INFO - no previously-included directories found matching '.git*' 12:36:20 INFO - Successfully installed jsonschema functools32 12:36:20 INFO - Cleaning up... 12:36:20 INFO - Return code: 0 12:36:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:20 INFO - 'HOME': '/Users/cltbld', 12:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:20 INFO - 'LOGNAME': 'cltbld', 12:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:20 INFO - 'MOZ_NO_REMOTE': '1', 12:36:20 INFO - 'NO_EM_RESTART': '1', 12:36:20 INFO - 'PAGER': '/bin/cat', 12:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:20 INFO - 'PWD': '/builds/slave/test', 12:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:20 INFO - 'SHELL': '/bin/bash', 12:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:20 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:20 INFO - 'USER': 'cltbld', 12:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:20 INFO - 'XPC_FLAGS': '0x0', 12:36:20 INFO - 'XPC_SERVICE_NAME': '0', 12:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:36:20 INFO - Cleaning up... 12:36:20 INFO - Return code: 0 12:36:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:20 INFO - 'HOME': '/Users/cltbld', 12:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:20 INFO - 'LOGNAME': 'cltbld', 12:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:20 INFO - 'MOZ_NO_REMOTE': '1', 12:36:20 INFO - 'NO_EM_RESTART': '1', 12:36:20 INFO - 'PAGER': '/bin/cat', 12:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:20 INFO - 'PWD': '/builds/slave/test', 12:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:20 INFO - 'SHELL': '/bin/bash', 12:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:20 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:20 INFO - 'USER': 'cltbld', 12:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:20 INFO - 'XPC_FLAGS': '0x0', 12:36:20 INFO - 'XPC_SERVICE_NAME': '0', 12:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:36:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:36:23 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 12:36:23 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 12:36:23 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 12:36:23 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 12:36:23 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 12:36:23 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 12:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:36:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:36:24 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 12:36:24 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 12:36:24 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 12:36:24 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 12:36:24 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 12:36:24 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 12:36:24 INFO - Downloading docopt-0.6.1.tar.gz 12:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:36:24 INFO - Installing collected packages: blobuploader, requests, docopt 12:36:24 INFO - Running setup.py install for blobuploader 12:36:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:36:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:36:24 INFO - Running setup.py install for requests 12:36:25 INFO - Running setup.py install for docopt 12:36:25 INFO - Successfully installed blobuploader requests docopt 12:36:25 INFO - Cleaning up... 12:36:25 INFO - Return code: 0 12:36:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:25 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:25 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 12:36:25 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 12:36:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:25 INFO - 'HOME': '/Users/cltbld', 12:36:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:25 INFO - 'LOGNAME': 'cltbld', 12:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:25 INFO - 'MOZ_NO_REMOTE': '1', 12:36:25 INFO - 'NO_EM_RESTART': '1', 12:36:25 INFO - 'PAGER': '/bin/cat', 12:36:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:25 INFO - 'PWD': '/builds/slave/test', 12:36:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:25 INFO - 'SHELL': '/bin/bash', 12:36:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:25 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:25 INFO - 'USER': 'cltbld', 12:36:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:25 INFO - 'XPC_FLAGS': '0x0', 12:36:25 INFO - 'XPC_SERVICE_NAME': '0', 12:36:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:36:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-GEjeAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:36:25 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-r_bzix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-UWiq83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-fGQbSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-CU27Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-gDcxM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-GOtyfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-dPITws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MQEjkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-yCWtfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-LdyXAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:36:26 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-PQYX6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:36:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MJjx6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:36:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-M7zU1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-f3JJAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:36:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-iWVGvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:36:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-qQYZGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:36:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:36:27 INFO - Running setup.py install for manifestparser 12:36:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:36:27 INFO - Running setup.py install for mozcrash 12:36:27 INFO - Running setup.py install for mozdebug 12:36:28 INFO - Running setup.py install for mozdevice 12:36:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Running setup.py install for mozfile 12:36:28 INFO - Running setup.py install for mozhttpd 12:36:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Running setup.py install for mozinfo 12:36:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Running setup.py install for mozInstall 12:36:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:36:28 INFO - Running setup.py install for mozleak 12:36:28 INFO - Running setup.py install for mozlog 12:36:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Running setup.py install for moznetwork 12:36:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Running setup.py install for mozprocess 12:36:29 INFO - Running setup.py install for mozprofile 12:36:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Running setup.py install for mozrunner 12:36:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:36:29 INFO - Running setup.py install for mozscreenshot 12:36:29 INFO - Running setup.py install for moztest 12:36:30 INFO - Running setup.py install for mozversion 12:36:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:36:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:36:30 INFO - Cleaning up... 12:36:30 INFO - Return code: 0 12:36:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:36: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')]} 12:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36: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')]} 12:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:30 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:30 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 12:36:30 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 12:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:30 INFO - 'HOME': '/Users/cltbld', 12:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:30 INFO - 'LOGNAME': 'cltbld', 12:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:30 INFO - 'MOZ_NO_REMOTE': '1', 12:36:30 INFO - 'NO_EM_RESTART': '1', 12:36:30 INFO - 'PAGER': '/bin/cat', 12:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:30 INFO - 'PWD': '/builds/slave/test', 12:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:30 INFO - 'SHELL': '/bin/bash', 12:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:30 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:30 INFO - 'USER': 'cltbld', 12:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:30 INFO - 'XPC_FLAGS': '0x0', 12:36:30 INFO - 'XPC_SERVICE_NAME': '0', 12:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:36:30 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-RoXccV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:36:30 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)) 12:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:36:30 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-_OSAKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:36:30 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)) 12:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:36:30 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-XKapnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:36:30 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)) 12:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:36:30 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-UKivF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:36:30 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-nutZkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-pnTxSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-osmkjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-mKNBIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-1hyi9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-7Z25D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-xqwTDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-9meind-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MGjZP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-WFZowt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:36:31 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)) 12:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:31 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-aH_VQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:32 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)) 12:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:36:32 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-FcnMHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:36:32 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-r5mtot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:36:32 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)) 12:36:32 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)) 12:36:32 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)) 12:36:32 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)) 12:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:36:32 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 12:36:32 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 12:36:32 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 12:36:32 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 12:36:32 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 12:36:32 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 12:36:34 INFO - Downloading blessings-1.6.tar.gz 12:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:36:34 INFO - Installing collected packages: blessings 12:36:34 INFO - Running setup.py install for blessings 12:36:34 INFO - Successfully installed blessings 12:36:34 INFO - Cleaning up... 12:36:35 INFO - Return code: 0 12:36:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:36:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:36:35 INFO - Reading from file tmpfile_stdout 12:36:35 INFO - Current package versions: 12:36:35 INFO - blessings == 1.6 12:36:35 INFO - blobuploader == 1.2.4 12:36:35 INFO - docopt == 0.6.1 12:36:35 INFO - functools32 == 3.2.3-2 12:36:35 INFO - jsonschema == 2.5.1 12:36:35 INFO - manifestparser == 1.1 12:36:35 INFO - mozInstall == 1.12 12:36:35 INFO - mozcrash == 1.0 12:36:35 INFO - mozdebug == 0.1 12:36:35 INFO - mozdevice == 0.49 12:36:35 INFO - mozfile == 1.2 12:36:35 INFO - mozhttpd == 0.7 12:36:35 INFO - mozinfo == 0.9 12:36:35 INFO - mozleak == 0.1 12:36:35 INFO - mozlog == 3.4 12:36:35 INFO - moznetwork == 0.27 12:36:35 INFO - mozprocess == 0.23 12:36:35 INFO - mozprofile == 0.28 12:36:35 INFO - mozrunner == 6.13 12:36:35 INFO - mozscreenshot == 0.1 12:36:35 INFO - mozsystemmonitor == 0.3 12:36:35 INFO - moztest == 0.7 12:36:35 INFO - mozversion == 1.4 12:36:35 INFO - psutil == 3.1.1 12:36:35 INFO - requests == 1.2.3 12:36:35 INFO - wsgiref == 0.1.2 12:36:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:36:35 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')]} 12:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:35 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')]} 12:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:35 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:35 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 12:36:35 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 12:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:35 INFO - 'HOME': '/Users/cltbld', 12:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:35 INFO - 'LOGNAME': 'cltbld', 12:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:35 INFO - 'MOZ_NO_REMOTE': '1', 12:36:35 INFO - 'NO_EM_RESTART': '1', 12:36:35 INFO - 'PAGER': '/bin/cat', 12:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:35 INFO - 'PWD': '/builds/slave/test', 12:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:35 INFO - 'SHELL': '/bin/bash', 12:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:35 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:35 INFO - 'USER': 'cltbld', 12:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:35 INFO - 'XPC_FLAGS': '0x0', 12:36:35 INFO - 'XPC_SERVICE_NAME': '0', 12:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 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)) 12:36:35 INFO - Cleaning up... 12:36:35 INFO - Return code: 0 12:36:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:36:35 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')]} 12:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:35 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')]} 12:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:35 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 0x103ce43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd432d83cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, '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.k9MT6b1uTE/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.EcASGhfDx2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 12:36:35 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 12:36:35 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 12:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:35 INFO - 'HOME': '/Users/cltbld', 12:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:35 INFO - 'LOGNAME': 'cltbld', 12:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:35 INFO - 'MOZ_NO_REMOTE': '1', 12:36:35 INFO - 'NO_EM_RESTART': '1', 12:36:35 INFO - 'PAGER': '/bin/cat', 12:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:35 INFO - 'PWD': '/builds/slave/test', 12:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:35 INFO - 'SHELL': '/bin/bash', 12:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:36:35 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:35 INFO - 'USER': 'cltbld', 12:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:35 INFO - 'XPC_FLAGS': '0x0', 12:36:35 INFO - 'XPC_SERVICE_NAME': '0', 12:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:36:35 INFO - Cleaning up... 12:36:35 INFO - Return code: 0 12:36:35 INFO - Running post-action listener: _resource_record_post_action 12:36:35 INFO - Running post-action listener: _start_resource_monitoring 12:36:35 INFO - Starting resource monitoring. 12:36:35 INFO - [mozharness: 2016-11-22 20:36:35.921986Z] Finished create-virtualenv step (success) 12:36:35 INFO - [mozharness: 2016-11-22 20:36:35.922973Z] Running install step. 12:36:35 INFO - Running pre-action listener: _resource_record_pre_action 12:36:35 INFO - Running main action method: install 12:36:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:36:36 INFO - Reading from file tmpfile_stdout 12:36:36 INFO - Detecting whether we're running mozinstall >=1.0... 12:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:36:36 INFO - Reading from file tmpfile_stdout 12:36:36 INFO - Output received: 12:36:36 INFO - Usage: mozinstall [options] installer 12:36:36 INFO - Options: 12:36:36 INFO - -h, --help show this help message and exit 12:36:36 INFO - -d DEST, --destination=DEST 12:36:36 INFO - Directory to install application into. [default: 12:36:36 INFO - "/builds/slave/test"] 12:36:36 INFO - --app=APP Application being installed. [default: firefox] 12:36:36 INFO - mkdir: /builds/slave/test/build/application 12:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:37:08 INFO - Reading from file tmpfile_stdout 12:37:08 INFO - Output received: 12:37:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:37:08 INFO - Running post-action listener: _resource_record_post_action 12:37:08 INFO - [mozharness: 2016-11-22 20:37:08.892550Z] Finished install step (success) 12:37:08 INFO - [mozharness: 2016-11-22 20:37:08.892721Z] Running run-tests step. 12:37:08 INFO - Running pre-action listener: _resource_record_pre_action 12:37:08 INFO - Running main action method: run_tests 12:37:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:08 INFO - 2016-11-22 12:37:08.907 screenresolution[1904:16542] starting screenresolution argv=screenresolution get 12:37:08 INFO - 2016-11-22 12:37:08.910 screenresolution[1904:16542] Display 0: 1600x1200x32@50 12:37:08 INFO - 2016-11-22 12:37:08.916 screenresolution[1905:16544] starting screenresolution argv=screenresolution list 12:37:08 INFO - Available Modes on Display 0 12:37:08 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 12:37:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:08 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 12:37:08 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 12:37:09 INFO - Graphics/Displays: 12:37:09 INFO - Intel Iris: 12:37:09 INFO - Chipset Model: Intel Iris 12:37:09 INFO - Type: GPU 12:37:09 INFO - Bus: Built-In 12:37:09 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:09 INFO - Vendor: Intel (0x8086) 12:37:09 INFO - Device ID: 0x0a2e 12:37:09 INFO - Revision ID: 0x0009 12:37:09 INFO - Displays: 12:37:09 INFO - Display: 12:37:09 INFO - Resolution: 1600 x 1200 @ 50 Hz 12:37:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:09 INFO - Display Serial Number: 1600x1200 50 12:37:09 INFO - Main Display: Yes 12:37:09 INFO - Mirror: Off 12:37:09 INFO - Online: Yes 12:37:09 INFO - Rotation: Supported 12:37:09 INFO - Return code: 0 12:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:37:09 INFO - Python 2.7.3 12:37:09 INFO - Return code: 0 12:37:09 INFO - grabbing minidump binary from tooltool 12:37:09 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')]} 12:37:09 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 0x103afad30>, '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 0x103961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103af2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:37:09 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 12:37:09 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 12:37:09 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 12:37:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:37:09 INFO - Return code: 0 12:37:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:37:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:37:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:37:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:37:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 12:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 12:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k9MT6b1uTE/Render', 12:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:09 INFO - 'HOME': '/Users/cltbld', 12:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:09 INFO - 'LOGNAME': 'cltbld', 12:37:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:37:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:09 INFO - 'MOZ_NO_REMOTE': '1', 12:37:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:37:09 INFO - 'NO_EM_RESTART': '1', 12:37:09 INFO - 'PAGER': '/bin/cat', 12:37:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:09 INFO - 'PWD': '/builds/slave/test', 12:37:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:09 INFO - 'SHELL': '/bin/bash', 12:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EcASGhfDx2/Listeners', 12:37:09 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 12:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:09 INFO - 'USER': 'cltbld', 12:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:09 INFO - 'XPC_FLAGS': '0x0', 12:37:09 INFO - 'XPC_SERVICE_NAME': '0', 12:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 12:37:09 INFO - mozversion application_buildid: 20161122075551 12:37:09 INFO - mozversion application_changeset: 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 12:37:09 INFO - mozversion application_display_name: Nightly 12:37:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:37:09 INFO - mozversion application_name: Firefox 12:37:09 INFO - mozversion application_remotingname: firefox 12:37:09 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 12:37:09 INFO - mozversion application_vendor: Mozilla 12:37:09 INFO - mozversion application_version: 53.0a1 12:37:09 INFO - mozversion platform_buildid: 20161122075551 12:37:09 INFO - mozversion platform_changeset: 513016d8b5ce2f4e3cc50cb83abd4c2dcf661325 12:37:09 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 12:37:09 INFO - mozversion platform_version: 53.0a1 12:37:09 INFO - using testdate: 1479847029 12:37:09 INFO - actual date: 1479847029 12:37:09 INFO - starting webserver on 'localhost:52400' 12:37:09 INFO - SUITE-START | Running 2 tests 12:37:09 INFO - TEST-START | dromaeo_css 12:37:09 INFO - operating with platform_type : mac_ 12:37:09 INFO - Initialising browser for dromaeo_css test... 12:37:09 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpIeHDFJ/profile http://localhost:52400/getInfo.html) 12:37:12 INFO - PROCESS | 1915 | 2016-11-22 12:37:12.738 plugin-container[1917:16702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 12:37:12 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:37:12 INFO - PROCESS | 1915 | 2016-11-22 12:37:12.741 plugin-container[1917:16702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9627, name = 'com.apple.CFPasteboardClient' 12:37:12 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:37:12 INFO - PROCESS | 1915 | 2016-11-22 12:37:12.741 plugin-container[1917:16702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:37:12 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 12:37:12 INFO - PROCESS | 1915 | colorDepth:24 12:37:12 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 12:37:12 INFO - PROCESS | 1915 | __metrics 12:37:12 ERROR - PROCESS | 1915 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 12:37:13 INFO - TEST-INFO | 1915: exit 0 12:37:13 INFO - Browser initialized. 12:37:13 INFO - Running cycle 1/1 for dromaeo_css test... 12:37:13 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpIeHDFJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:16 INFO - PROCESS | 1918 | 2016-11-22 12:37:16.347 plugin-container[1919:16819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 12:37:16 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:37:16 INFO - PROCESS | 1918 | 2016-11-22 12:37:16.347 plugin-container[1919:16819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:37:16 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:37:16 INFO - PROCESS | 1918 | 2016-11-22 12:37:16.347 plugin-container[1919:16819] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:37:26 INFO - PROCESS | 1918 | RSS: Main: 242544640 12:37:26 INFO - PROCESS | 1918 | 12:38:15 INFO - PROCESS | 1918 | 1479847095639 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:40:29 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52400/tests/dromaeo/cssquery-ext.html) 12:40:31 INFO - PROCESS | 1918 | RSS: Main: 246927360 12:40:31 INFO - PROCESS | 1918 | 12:43:33 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-ext.html (next: http://localhost:52400/tests/dromaeo/cssquery-jquery.html) 12:43:34 INFO - PROCESS | 1918 | RSS: Main: 259371008 12:43:34 INFO - PROCESS | 1918 | 12:46:36 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52400/tests/dromaeo/cssquery-mootools.html) 12:46:36 INFO - PROCESS | 1918 | RSS: Main: 263761920 12:46:36 INFO - PROCESS | 1918 | 12:49:38 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52400/tests/dromaeo/cssquery-prototype.html) 12:49:39 INFO - PROCESS | 1918 | RSS: Main: 263114752 12:49:39 INFO - PROCESS | 1918 | 12:52:42 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52400/tests/dromaeo/cssquery-yui.html) 12:52:42 INFO - PROCESS | 1918 | RSS: Main: 267616256 12:52:42 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52400/tests/dromaeo/cssquery-yui.html (next: http://localhost:52400/tests/dromaeo/cssquery-dojo.html) 12:55:45 INFO - PROCESS | 1918 | RSS: Main: 270008320 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | __start_tp_report 12:55:45 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 12:55:45 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 12:55:45 INFO - PROCESS | 1918 | |i|pagename|runs| 12:55:45 INFO - PROCESS | 1918 | |0;dojo.html;3356.992902129362;3399.4560870260734;3403.3193361327735;3404.200012996945;3418.2138108235113;4588.898664320555;5578.553715702744;5623.156526521024;5649.576281778867;5735.942640573593;5638.379778224392;5723.112917497784;5753.913691294634;5816.040353341694;5861.824145275638;4053.97973010136;4181.498220213579;4981.427135879384;5185.922211166836;5331.147016477365;5148.459411761767;5300.973495132519;5320.9467905320835;5364.409914909357;5380.542653874425;5170.9741451292875;5312.814051508218;5325.627206095575;5326.7070311132875;5344.7862085516535;4009.278329900617;5140.228965655144;5160.122779127557;5231.136862417697;5252.343457067866;4083.183363327317;4476.865694029184;5052.216906379517;5199.66202196856;5218.452062533455;5567.164925261202;5715.771369145229;5718;5749.59752817303;5755.280589926259;5066.847994560169;5191.896162076738;5946.5540084493405;6161.9691901540655;6218.751249949996;4854.975725121387;5942.900563395786;6118.131909153803;6158.7228574714245;6172.537059720494;5029.924551131736;5844.532437404998;5864.237649105632;5867.73595188209;5871.442212989759;6016.548758843104;6216.813495595139;6326.620402775849;6332.240131184288;6357.841053973688;5454.427285135083;6099.146119543268;6115.755369785248;6121.296050954197;6130.275842464065;6098.207233059674;6216.471599913967;6278.15244941927;6297.874042519124;6303.810885673437;6042.305134909541;6118.1434599156155;6156.015037593964;6169.81490555284;6194.628322300677;6242.875142497215;6252.593581417284;6261.123442718023;6272;6287.088372186007;4862.610991120702;4881.8535443936025;4903.583195428429;4996.850094497171;5018.272350509155;4368.60682538573;4404.757738324359;4429.867103986886;4457.197704413236;4466.553344665507;5724.713764311768;5881.411858814085;5895.086261629454;5913.29040515141;5951.256092988377;5186.507281808223;6247.531435142382;6253.374662533801;6257.56197066201;6280.057991301342;5669.177969194444;5693.886122277614;5717.028105222123;5779.3642699303045;5880.617759845596;5325.520703136736;5379.919301210485;5388.757505912244;5420.34955805298;5463.754131064112;6349.492040636738;6364.395382438661;6368.395002474757;6371.331010243857;6386.233651961795;6250.906236406367;6252.468540174136;6260.937390626035;6263.311035786151;6266.091416744455;6190.81427557174;6224.408681175281;6243.500519958483;6249.218847644044;6376.649284289409;6152.98475751496;6204.286507051746;6208.317085120543;6225.62646241227;6248.9062664059165;6177.907331390123;6229.909765790878;6232.096346029891;6245.094461303176;6247.000479923372;5919.200907877382;5946.702664866826;5990.580659353804;6123.693815309306;6138.447539721446;6045.093236014633;6055;6061.545384096122;6085.904537183173;6105.725242364016;4329.826806927687;5419.753456705059;5951.137085122719;5991.700414979146;6062.242219722535;5189.910118874927;6073.605215660968;6117.143599895929;6155.138280640804;6157.692115394123;5948.762049517971;5964.731587078505;5988.191594134736;6004.969975150097;6015.187949626745 12:55:45 INFO - PROCESS | 1918 | |1;ext.html;484.13581756564537;505.9873503162421;512.2521119166015;519.3819354967588;532.7496076843887;29841.552376714368;39845.804625861274;39919.600803991954;39960.60039399609;39980.60019399812;40752.592474075325;41287.38068928968;41312;41314.17371652565;41324.79337603308;9224.169824715775;9330.626774928995;9378.249740020816;9380.671676491324;9424.623015079389;9324.25405967521;9402.576884040234;9402.62389504419;9417.340786144972;9431.858522122173;8026.678932842679;9351.76620584484;9375.10936461035;9405.623775048989;9414.952925235399;9134;9213.2168765655;9232.676856309998;9251.444913305224;9265.814683706321;8435.493870367798;8701.216890479824;9109.223701378234;9139.040400757958;9216.723498295061;38828.41757373641;38993.80503097466;39181.608183918084;39202.60797392018;39287;2686.6104414859738;2816.788740844445;3135.3415782685665;3186.203449137716;3187.569678093451;25487.745122548724;29853.40293194146;29952.8502357489;30294.091177264716;30720.69279307201;2884.639420072491;2937.0748214312425;2960.3487232808748;2974.568687540316;2994.82031078136;332529;336491;336769.6323036763;338888;340708;50745.74627126841;59326.81346373048;59505;59598.404015959284;59695.70152149211;31067.223319416742;31754.682453175174;50067.74966125146;61377;61409.69295153585;15504.844951550565;15853.286602102933;15869;15899.28453219608;15940.963837350535;12234.204776689487;12294.631161065183;12319.753604927851;12320.137590368768;12440;18838;18880.62238755245;18952.14715337813;18954.715679264824;18962.71555926662;6521.673916304166;6791.456683465311;7402.814929626802;7694.538327700356;7799.727009554638;739.5747445219021;861.7802460372565;879.5118709116374;951.4434056077272;1019.3476175247847;22162.891855407164;24466.87766561156;28433.00484483033;28643.427131457254;30263.848680756455;1011.5650270383659;1018.0097908588691;1020.8519764634235;1022.9846552301722;1052.710504611238;11595.478203480863;12455.626331210078;13513.52702655422;15529.679912793712;15849.920750396173;127307;176159;176201;177298;181283;55214;55308;55683.721581392645;55748.44251557432;55947.72026139925;1026.8357062869904;1117.1063149480383;1248.8001919692806;1298.571471414431;1344.2405041151717;21011.05450254773;21069.367918962456;30254.243643909078;31225.8438707805;32564.83717581397;55215.72392138014;55956.88086238334;56094;56169;56321.15518267147;608740;612687;613335;613436;615844;40201.798991004856;40777.184456311305;41340.966475838344;41495.792521037205;41538;45067.54932450633;45822;46210.68946552592;46733.06533869372;47167.76416117897;32432;38017;38149.23701526009;38885.41671874981;38959.2208155841;37723.81138094292;37861.81069094637;37931.2413751729;38838.22323553456;38979;30125;31508.26704531273;32332.83833580817;33346.666533334355;37573.2485350286;31092.68907310898;32158.51762223615;34204;34258.16999980069;34821.82589087039;22931;24099.277021689377;25039;25095.49809003773;25336.873315633304 12:55:45 INFO - PROCESS | 1918 | |2;jquery.html;316.6153123954395;356.80910712768656;364.26782167842634;381.7766606535177;394.9395872083456;15700.921495392535;17887.821121788777;18473.445796626107;19486.830790152584;19672.70490942637;18687.532811679714;18883.62232755344;19014.619707605838;19043.61912761744;19219.519512012208;5289.894202115975;5378.139497680372;5937.643741375509;5950.196723442345;5968.462838344548;4998.325226094467;5577.776888924458;5787.450192231731;5806.477417032466;5875.94233038053;4928.97535512322;5808.361080281165;5819.243498345209;5828.679422631753;5843.2403787507565;5502.4043027521975;5606.046972014747;5683.5169010634145;5709;5709.25779648643;4955.82654607087;4974.452810190876;4990.476000020019;4997.525235102659;4998.500149985009;22726.204582839688;23132.884335578383;23860.64209036866;23913.88043059791;23975.921083551108;1871.429214089702;2397.568437681217;2423.503181847717;2986.313147975956;3111.0822307419367;14203.147811131263;14733.484328048467;14921.850781492154;15052.32264548098;15389.461368852037;1821.5355084453513;1840.2546968477789;1845.2065611786927;1859.8140185981429;1861.7672790901138;115156;116273;116645.41677291559;116659;118979;8179.263866251947;10206.948965255126;10236.129928955974;10687.198460115367;10897.509612067317;12933.224006559449;12960.28718420477;12962.935185324202;12968.740625187444;13008.54470093561;6810.284920083419;7759.961200193963;8068.0721717002125;8166.9591652042545;8223.17768223173;7380.741674041383;7895.60521973899;8048.315893149031;8087.636056377477;8257.133001034925;5229.50319719626;5254.737263136828;6194.81415557534;6331.366863313632;6410.358964103552;1796.568823482355;1830.844378227839;1833.220881125543;1834.596388794463;1842.263094762106;880.4101252161067;909.6816114359921;912.4388501071926;952.6856137474617;975.9853602195973;15981.680366392608;20603.690944635844;20740.688889666668;20984.160633574793;21106.366808995754;818.705266104202;854.3421565394611;926.7636752628172;942.2885721280527;986.3194395866828;8311.709090181936;9364.531773411301;10268.383896966057;10860.076893463985;11038.613648522263;578605.1069744624;578626;585088;597333;597483;111897;113733;114879;115923;119859;957.8467445208734;964.8118616869783;993.5826952679892;995.3381001633977;1005.070309963281;17420.390286339916;18300.542486437946;18357.54106147357;18385;18407.447776566725;49625.75187124186;49914.01203831539;51038.74480627573;51487.45537633877;51780.96438071142;48928.75535622299;65499;71325.6433717828;73073.6346318265;79735.60132199535;8902.42134261271;11347.943260283646;11596.188266821242;11738.060955123741;12082.939585302018;10283.382997020202;11413.543458261576;13138.474461021453;13209.537666181637;13410.865891340962;11312.27332040057;11477.885221148015;11542.769144616892;11682.415879206175;11757.059435245332;9792.657256995972;10529.36823790575;10973.177011723992;11412.942935285271;11435.313881167156;11032.944835276094;11428.914253145938;11585.6524304271;11587.246828956091;11823.940880295888;10111.59553617847;11665.300081995107;11672.299662020307;12048.277103373826;12055.97524210452;11328.660140195807;11568.107973006749;11568.47941842636;11592.942035289769;11660.825087623523 12:55:45 INFO - PROCESS | 1918 | |3;mootools.html;1189.1022278179973;1451.65886016786;1609.1793185475412;1750.894946303221;1765.3909401256562;7591.772246832604;7814.765557033284;8046.5574393408415;8502.744917652473;8511.361647876418;8008.399370047241;8293.502389856598;8314.792130196749;8398.49609023459;8448.11294814045;1947.7273181754565;2268.1381075191402;2297.655351697242;2302.355340504653;2311.144876395736;2242.473018840571;2278.5784629843533;2292.3695983604475;2295;2302.0216408026604;2281.5094754627753;2282.417983414227;2304.6427803690453;2318.5247024359965;2320.037184568402;2262.162999690109;2266.841321107523;2293.0713061210154;2293.4839661076126;2294.8737819419925;2305.1931823861682;2324.1284518305633;2336.0655737704883;2336.836421450499;2338.6375111857687;8328.33373330132;8355;8403.495790252604;8409.663613455516;8423.199796019368;5047.410065829252;5205.661631993946;5208.166693329087;5221.138512145491;5229.980153869997;3002.6546947101147;3134.200778801414;3401.1667141550233;3409.7272218222483;3423.9486407703903;949.3781573069581;962.5379817687549;992.6426486464867;999.5801763259376;1000.6747806962693;9739.26955478342;10832.72918177044;10945.01494865466;10954.123670106372;10981.835272470918;4567.725936443824;4721.268203428474;4734.763261836894;4743.762811859393;4775.594074503637;7710.53736775784;8630.568471576396;8743;8786.472811631322;8803.691870784492;5131.409887862869;5134.922976155361;5137.51193636604;5139.922901156486;5188.870278243074;4600.332951721982;4737.123632128068;4792.113459010095;4826.300186472942;4845.781939812717;3193.2495863472063;3584.086058055231;3667.0832291927018;3696.057505336122;3735.495708079429;1150.1948635954866;1157.1379322404862;1164.0512981919708;1169.3276366089533;1174.9060075193963;738.5494848142629;880.1198801198801;884.8805411269525;897.5467388968659;902.8555431131119;5424.349078110573;5507.201455788888;5691.544676425876;5703.657780533182;5871.677057761779;961.624966263158;963.643451922779;973.5667627905656;983.537737263485;1062.1396668698378;3523.5947865995226;3639.090227443139;3641.344557979536;3711;3712.424574191005;9966.2525310602;10303.278770486144;10303.536340864679;10317.896821031694;10357.896421035844;9571.856422153673;10092.899071009197;10277.588896444058;10281.434521101412;10395.68812935598;7667.846643067108;7869.331106855867;7895.407844411692;7902.762917112495;7941.126476087626;4375.365571992107;5010.69935803853;5603.719814009365;5607.775688972395;5704.2014118024335;9034.548272586213;9127.77180570491;9225.72322830316;9258.629654813732;9386.67146649864;8229.753107406787;9354.672586459441;9384.436933783722;9401.576929038072;9415.670451534163;6256.562040657111;7240.239774823778;7241.348278654947;7259.2377800332315;7268.273172682901;7786.805329866798;8010.599470026592;8032.397570182143;8114.350851931949;8166.469179503313;6241.750329986931;7070.8232294191;7111.679974401268;7217.566945983258;7275.527090739085;7032.789016329519;7066.081409416743;7080.4335653148655;7096.148462184571;7096.680649370687;5720.256366672306;6631.502637302126;6651.301613330531;6657.434118099822;6724.663766811737;4656.580907718321;4664.696794708333;4702.200625893674;4748.0978614063215;4802.567768900815;4665.113628410591;4786.760661966818;4802.327674125555;4832.782524786323;4915.95084049155 12:55:45 INFO - PROCESS | 1918 | |4;prototype.html;271.3149298022494;298.08487942018;306.904859493557;308.6697233959664;313.8117129722167;5353.116735738598;5408.2698835657175;5410.161424979125;5432.266644003058;5448.918266226;4331.7400955942585;4981.651284410093;5219.973900130494;5277.788888444457;5310.654807437514;3554.715622750187;3871.8838434846857;3884.950314003501;4015.698822588303;4050.8784736457956;3226.370857682753;3362.9406736877927;3481.56480439945;4122.670186385097;4129.967508122969;3867.38121900496;4081.857135000276;4086.504844670868;4098.774567398792;4103.343465045593;3591.586967498745;3597.4963505109304;3743.3636281832155;3903;4069.47096877407;2914.6793852676187;3252.8536215870345;3329.9344209852857;3522.9119272018147;3542;5055.469175736532;5095.439501654815;5130.333056702642;5144.43411224765;5173.560247378978;4335.739855608674;4546.640477702461;4573.062522182944;4613.538646135393;4667.486576476585;5441.571809759826;5494.5054945054735;5497.230387745719;5546.1143211308345;5643.472829481574;3578.856845726194;3581.5881173665102;3753.962460375416;3919.510061242345;3923.391874259466;4923.4091908970795;5147.91893702323;5684.2326285951285;5959.433853783884;5986.610870293417;3320.5517255170726;3322.368749937504;3880.825362858678;3917.7845218513257;3919.451276821247;4015.1128969384345;4015.5382131055303;4017.216642754635;4035.5157381114454;4070.1859628074503;4288.5711428857485;4578.649298456963;4621.30680397943;4664.580187783048;4692.155412025868;4460.553944605578;4743.079052983548;4761.35721677576;5401.838604700009;5658.321001479849;1006.0492834271544;1129.8532787703266;1258.182181581979;1266.7213213093105;1285.4729560879994;825.529448214524;830.7756905635565;860.8450478913855;875.4965894610497;883.7715575350268;4409.470863496401;4487.304467807495;4815.807367705253;5351.411344752074;5362.93526992103;4685.367475390779;5806.854828629233;5841.757824407495;5884.764609415661;5901.76392944286;4455.532169122195;4460.977695111504;4707.011527579247;4922.50774922512;5023.949760502422;2485.403503159265;2591.481703659276;2728.6452761141322;2733.6309598204384;2916.8395738234176;4497.437820272466;4982.476839931827;5171.595682511785;5736.42635736423;5746.195532625352;4055.148418832019;5196.506331898464;5309.425103641239;5425.849225342616;5657.405972372924;4985.875353116201;5041.6470847040355;5106.974465127725;5656.632318899258;5696.0095949626775;5069.518395752398;5457.908418316432;5612.186232996274;5648.406917273627;5668.971655141698;4563.977180114078;5292.7353632319;5410.3768869338255;5478.397376288525;5770.32660528442;6075.422834830684;6130.877382452415;6158.445739883432;6176.938230617637;6177.336111235988;3769.1896242308035;3957.881263562098;3962.445257663987;3970.325044742343;3986.242613903349;4923.769057735566;4949.777260023236;5237.973810130925;5880.764769409176;5920.526357891272;4739.857804265877;5171.81898633546;5499.230107784833;5669.688167150846;5697.772089116389;4914.606831453403;5161.2193793141305;5686.886262274814;5694.579803583543;5700.486956173964;5311.814086506954;5450.737943753298;5670;5679.971600142138;5719.942800572107;4315.417418648442;5011.949880501148;5373.7850485980125;5579.46995035471;5697.259356283822;4797.688150270221;4837.032593481388;5249.055170069424;5270.947290527046;5590.692511911884 12:55:45 INFO - PROCESS | 1918 | |5;yui.html;664.9734010639575;699.805388401945;722.1911459165735;746.3059354800041;766.0003695177791;13442.932785336072;14277.571672849805;14301.284935753196;14347.354369053392;14365.569032929016;12731.2997785122;12826.973842092633;13582.388792504336;14156.575302740908;14297.428102875872;804.5816175588704;815.4658698552457;824.2046425199682;826.9952008309903;827;645.3643161485955;816.9714060007901;819.9180081991813;821.3593397150232;821.3798582070558;697.3061803505519;817.6892780743339;850.3962186847345;859.2224037246302;866.5710473315718;856.3149480415705;856.6273670953155;856.8671855862351;858.4034096303072;859.299670768328;632.3676323676324;635.6122765113278;636.3572791480592;637.9553431259814;638.1066506890344;11601.187916845824;13288.933555332258;13960.860391396058;13979.720405591934;13984.440622375092;9853.113219810251;11950.880491195063;12214.755704885942;12267.202631828992;12268.44791984363;11852.644420667393;11871.169018168732;11968.760624787456;11984.580539681156;12399.876001240053;3691.3171063353366;4251.617354438115;4354.738715677038;4417.911641767147;4455.532169122261;84328.57835710782;86363.13636863614;87349.05632249039;91155;92997.53501232518;9932.304738668225;9968.850467242997;10020.24848136378;10062.295639305325;10107.595696172219;18923.716144257847;18957.715634265496;19042.904785475985;19140.712889306673;19244.518887027938;7266.818329541698;7271.600061996641;7292.234315396807;7409.851802963911;7471.589062601501;4090.652294554937;4154.065335299533;4160.9375859362135;4190.664746820247;4193.874183774492;2838.389746204576;3107.300857307088;3198.616166060088;3213.5661685672603;3636.6726994570085;605.3371558143914;653.8953767397289;657.7336178847575;662.8508585568209;667.9599224046573;554.877926856091;567.4552429667565;568.4287291272327;632.5003247434568;661.4014317043084;7562.357199638092;8158.510489370538;8775.517346546001;8778.91221087781;8810.32603805275;552.4585905812327;590.6249531547412;616.9938300617026;658.6805399381296;663.6482664187988;3389.1357703785375;4352.325389564622;4464.785354544722;4507.965425400228;4597.540245975442;85787;94518;112499;117480;120420;101708.98291016996;103631.96368036223;105100;105205;105456.47271763592;66312;93011.53494232486;94229.0577094234;94477.5276123615;94601;8813.515256660945;8948.642054317754;9042.186203241477;9139.725808225763;9248.98261191282;40346.79826600848;44212.11575768531;45815.54184458113;46108.53891461177;49240.50759492359;58986.705066474395;72022;74788.62605686937;75924.24075759172;76894.615526922;11861;12095.334756588472;12144.453499592717;12287.201331913397;12456;11631.53473861036;13765.242911639956;13979.930100349433;14136.929315353358;14220;10596.523156458132;12254.326012069065;12448.564300249447;12778.61664150077;13062.34688265602;12250.4709036365;12256.877431225574;12314.445849936596;12370.81443778362;12585.370731463207;10404.531796069346;10543.31468454548;12461.252324860538;12576;13167.67080822987;9737.415755054719;9917.355371900803;10185.439800811026;10188.949055254676;10488.895111048792;10222.160566004153;10682.73293167677;10797;10850.45747712627;10864.619738309122 12:55:45 INFO - PROCESS | 1918 | __end_tp_report 12:55:45 INFO - PROCESS | 1918 | __start_cc_report 12:55:45 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,382 12:55:45 INFO - PROCESS | 1918 | __end_cc_report 12:55:45 INFO - PROCESS | 1918 | __startTimestamp1479848145899__endTimestamp 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | ------- Summary: start ------- 12:55:45 INFO - PROCESS | 1918 | Number of tests: 6 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5661.91 Median:5919.20 stddev:697.86 (11.8%) stddev-sans-first:676.17 12:55:45 INFO - PROCESS | 1918 | Values: 3357.0 3399.5 3403.3 3404.2 3418.2 4588.9 5578.6 5623.2 5649.6 5735.9 5638.4 5723.1 5753.9 5816.0 5861.8 4054.0 4181.5 4981.4 5185.9 5331.1 5148.5 5301.0 5320.9 5364.4 5380.5 5171.0 5312.8 5325.6 5326.7 5344.8 4009.3 5140.2 5160.1 5231.1 5252.3 4083.2 4476.9 5052.2 5199.7 5218.5 5567.2 5715.8 5718.0 5749.6 5755.3 5066.8 5191.9 5946.6 6162.0 6218.8 4855.0 5942.9 6118.1 6158.7 6172.5 5029.9 5844.5 5864.2 5867.7 5871.4 6016.5 6216.8 6326.6 6332.2 6357.8 5454.4 6099.1 6115.8 6121.3 6130.3 6098.2 6216.5 6278.2 6297.9 6303.8 6042.3 6118.1 6156.0 6169.8 6194.6 6242.9 6252.6 6261.1 6272.0 6287.1 4862.6 4881.9 4903.6 4996.9 5018.3 4368.6 4404.8 4429.9 4457.2 4466.6 5724.7 5881.4 5895.1 5913.3 5951.3 5186.5 6247.5 6253.4 6257.6 6280.1 5669.2 5693.9 5717.0 5779.4 5880.6 5325.5 5379.9 5388.8 5420.3 5463.8 6349.5 6364.4 6368.4 6371.3 6386.2 6250.9 6252.5 6260.9 6263.3 6266.1 6190.8 6224.4 6243.5 6249.2 6376.6 6153.0 6204.3 6208.3 6225.6 6248.9 6177.9 6229.9 6232.1 6245.1 6247.0 5919.2 5946.7 5990.6 6123.7 6138.4 6045.1 6055.0 6061.5 6085.9 6105.7 4329.8 5419.8 5951.1 5991.7 6062.2 5189.9 6073.6 6117.1 6155.1 6157.7 5948.8 5964.7 5988.2 6005.0 6015.2 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:53131.75 Median:38833.32 stddev:111616.47 (287.4%) stddev-sans-first:111859.59 12:55:45 INFO - PROCESS | 1918 | Values: 484.1 506.0 512.3 519.4 532.7 29841.6 39845.8 39919.6 39960.6 39980.6 40752.6 41287.4 41312.0 41314.2 41324.8 9224.2 9330.6 9378.2 9380.7 9424.6 9324.3 9402.6 9402.6 9417.3 9431.9 8026.7 9351.8 9375.1 9405.6 9415.0 9134.0 9213.2 9232.7 9251.4 9265.8 8435.5 8701.2 9109.2 9139.0 9216.7 38828.4 38993.8 39181.6 39202.6 39287.0 2686.6 2816.8 3135.3 3186.2 3187.6 25487.7 29853.4 29952.9 30294.1 30720.7 2884.6 2937.1 2960.3 2974.6 2994.8 332529.0 336491.0 336769.6 338888.0 340708.0 50745.7 59326.8 59505.0 59598.4 59695.7 31067.2 31754.7 50067.7 61377.0 61409.7 15504.8 15853.3 15869.0 15899.3 15941.0 12234.2 12294.6 12319.8 12320.1 12440.0 18838.0 18880.6 18952.1 18954.7 18962.7 6521.7 6791.5 7402.8 7694.5 7799.7 739.6 861.8 879.5 951.4 1019.3 22162.9 24466.9 28433.0 28643.4 30263.8 1011.6 1018.0 1020.9 1023.0 1052.7 11595.5 12455.6 13513.5 15529.7 15849.9 127307.0 176159.0 176201.0 177298.0 181283.0 55214.0 55308.0 55683.7 55748.4 55947.7 1026.8 1117.1 1248.8 1298.6 1344.2 21011.1 21069.4 30254.2 31225.8 32564.8 55215.7 55956.9 56094.0 56169.0 56321.2 608740.0 612687.0 613335.0 613436.0 615844.0 40201.8 40777.2 41341.0 41495.8 41538.0 45067.5 45822.0 46210.7 46733.1 47167.8 32432.0 38017.0 38149.2 38885.4 38959.2 37723.8 37861.8 37931.2 38838.2 38979.0 30125.0 31508.3 32332.8 33346.7 37573.2 31092.7 32158.5 34204.0 34258.2 34821.8 22931.0 24099.3 25039.0 25095.5 25336.9 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:33986.45 Median:21045.26 stddev:97679.58 (464.1%) stddev-sans-first:97920.88 12:55:45 INFO - PROCESS | 1918 | Values: 316.6 356.8 364.3 381.8 394.9 15700.9 17887.8 18473.4 19486.8 19672.7 18687.5 18883.6 19014.6 19043.6 19219.5 5289.9 5378.1 5937.6 5950.2 5968.5 4998.3 5577.8 5787.5 5806.5 5875.9 4929.0 5808.4 5819.2 5828.7 5843.2 5502.4 5606.0 5683.5 5709.0 5709.3 4955.8 4974.5 4990.5 4997.5 4998.5 22726.2 23132.9 23860.6 23913.9 23975.9 1871.4 2397.6 2423.5 2986.3 3111.1 14203.1 14733.5 14921.9 15052.3 15389.5 1821.5 1840.3 1845.2 1859.8 1861.8 115156.0 116273.0 116645.4 116659.0 118979.0 8179.3 10206.9 10236.1 10687.2 10897.5 12933.2 12960.3 12962.9 12968.7 13008.5 6810.3 7760.0 8068.1 8167.0 8223.2 7380.7 7895.6 8048.3 8087.6 8257.1 5229.5 5254.7 6194.8 6331.4 6410.4 1796.6 1830.8 1833.2 1834.6 1842.3 880.4 909.7 912.4 952.7 976.0 15981.7 20603.7 20740.7 20984.2 21106.4 818.7 854.3 926.8 942.3 986.3 8311.7 9364.5 10268.4 10860.1 11038.6 578605.1 578626.0 585088.0 597333.0 597483.0 111897.0 113733.0 114879.0 115923.0 119859.0 957.8 964.8 993.6 995.3 1005.1 17420.4 18300.5 18357.5 18385.0 18407.4 49625.8 49914.0 51038.7 51487.5 51781.0 48928.8 65499.0 71325.6 73073.6 79735.6 8902.4 11347.9 11596.2 11738.1 12082.9 10283.4 11413.5 13138.5 13209.5 13410.9 11312.3 11477.9 11542.8 11682.4 11757.1 9792.7 10529.4 10973.2 11412.9 11435.3 11032.9 11428.9 11585.7 11587.2 11823.9 10111.6 11665.3 11672.3 12048.3 12056.0 11328.7 11568.1 11568.5 11592.9 11660.8 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:5385.37 Median:5164.40 stddev:2963.65 (57.4%) stddev-sans-first:2955.18 12:55:45 INFO - PROCESS | 1918 | Values: 1189.1 1451.7 1609.2 1750.9 1765.4 7591.8 7814.8 8046.6 8502.7 8511.4 8008.4 8293.5 8314.8 8398.5 8448.1 1947.7 2268.1 2297.7 2302.4 2311.1 2242.5 2278.6 2292.4 2295.0 2302.0 2281.5 2282.4 2304.6 2318.5 2320.0 2262.2 2266.8 2293.1 2293.5 2294.9 2305.2 2324.1 2336.1 2336.8 2338.6 8328.3 8355.0 8403.5 8409.7 8423.2 5047.4 5205.7 5208.2 5221.1 5230.0 3002.7 3134.2 3401.2 3409.7 3423.9 949.4 962.5 992.6 999.6 1000.7 9739.3 10832.7 10945.0 10954.1 10981.8 4567.7 4721.3 4734.8 4743.8 4775.6 7710.5 8630.6 8743.0 8786.5 8803.7 5131.4 5134.9 5137.5 5139.9 5188.9 4600.3 4737.1 4792.1 4826.3 4845.8 3193.2 3584.1 3667.1 3696.1 3735.5 1150.2 1157.1 1164.1 1169.3 1174.9 738.5 880.1 884.9 897.5 902.9 5424.3 5507.2 5691.5 5703.7 5871.7 961.6 963.6 973.6 983.5 1062.1 3523.6 3639.1 3641.3 3711.0 3712.4 9966.3 10303.3 10303.5 10317.9 10357.9 9571.9 10092.9 10277.6 10281.4 10395.7 7667.8 7869.3 7895.4 7902.8 7941.1 4375.4 5010.7 5603.7 5607.8 5704.2 9034.5 9127.8 9225.7 9258.6 9386.7 8229.8 9354.7 9384.4 9401.6 9415.7 6256.6 7240.2 7241.3 7259.2 7268.3 7786.8 8010.6 8032.4 8114.4 8166.5 6241.8 7070.8 7111.7 7217.6 7275.5 7032.8 7066.1 7080.4 7096.1 7096.7 5720.3 6631.5 6651.3 6657.4 6724.7 4656.6 4664.7 4702.2 4748.1 4802.6 4665.1 4786.8 4802.3 4832.8 4916.0 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4437.15 Median:4923.59 stddev:1385.69 (28.1%) stddev-sans-first:1353.84 12:55:45 INFO - PROCESS | 1918 | Values: 271.3 298.1 306.9 308.7 313.8 5353.1 5408.3 5410.2 5432.3 5448.9 4331.7 4981.7 5220.0 5277.8 5310.7 3554.7 3871.9 3885.0 4015.7 4050.9 3226.4 3362.9 3481.6 4122.7 4130.0 3867.4 4081.9 4086.5 4098.8 4103.3 3591.6 3597.5 3743.4 3903.0 4069.5 2914.7 3252.9 3329.9 3522.9 3542.0 5055.5 5095.4 5130.3 5144.4 5173.6 4335.7 4546.6 4573.1 4613.5 4667.5 5441.6 5494.5 5497.2 5546.1 5643.5 3578.9 3581.6 3754.0 3919.5 3923.4 4923.4 5147.9 5684.2 5959.4 5986.6 3320.6 3322.4 3880.8 3917.8 3919.5 4015.1 4015.5 4017.2 4035.5 4070.2 4288.6 4578.6 4621.3 4664.6 4692.2 4460.6 4743.1 4761.4 5401.8 5658.3 1006.0 1129.9 1258.2 1266.7 1285.5 825.5 830.8 860.8 875.5 883.8 4409.5 4487.3 4815.8 5351.4 5362.9 4685.4 5806.9 5841.8 5884.8 5901.8 4455.5 4461.0 4707.0 4922.5 5023.9 2485.4 2591.5 2728.6 2733.6 2916.8 4497.4 4982.5 5171.6 5736.4 5746.2 4055.1 5196.5 5309.4 5425.8 5657.4 4985.9 5041.6 5107.0 5656.6 5696.0 5069.5 5457.9 5612.2 5648.4 5669.0 4564.0 5292.7 5410.4 5478.4 5770.3 6075.4 6130.9 6158.4 6176.9 6177.3 3769.2 3957.9 3962.4 3970.3 3986.2 4923.8 4949.8 5238.0 5880.8 5920.5 4739.9 5171.8 5499.2 5669.7 5697.8 4914.6 5161.2 5686.9 5694.6 5700.5 5311.8 5450.7 5670.0 5680.0 5719.9 4315.4 5011.9 5373.8 5579.5 5697.3 4797.7 4837.0 5249.1 5270.9 5590.7 12:55:45 INFO - PROCESS | 1918 | 12:55:45 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:20224.92 Median:24333.82 stddev:30449.20 (125.1%) stddev-sans-first:30499.20 12:55:45 INFO - PROCESS | 1918 | Values: 665.0 699.8 722.2 746.3 766.0 13442.9 14277.6 14301.3 14347.4 14365.6 12731.3 12827.0 13582.4 14156.6 14297.4 804.6 815.5 824.2 827.0 827.0 645.4 817.0 819.9 821.4 821.4 697.3 817.7 850.4 859.2 866.6 856.3 856.6 856.9 858.4 859.3 632.4 635.6 636.4 638.0 638.1 11601.2 13288.9 13960.9 13979.7 13984.4 9853.1 11950.9 12214.8 12267.2 12268.4 11852.6 11871.2 11968.8 11984.6 12399.9 3691.3 4251.6 4354.7 4417.9 4455.5 84328.6 86363.1 87349.1 91155.0 92997.5 9932.3 9968.9 10020.2 10062.3 10107.6 18923.7 18957.7 19042.9 19140.7 19244.5 7266.8 7271.6 7292.2 7409.9 7471.6 4090.7 4154.1 4160.9 4190.7 4193.9 2838.4 3107.3 3198.6 3213.6 3636.7 605.3 653.9 657.7 662.9 668.0 554.9 567.5 568.4 632.5 661.4 7562.4 8158.5 8775.5 8778.9 8810.3 552.5 590.6 617.0 658.7 663.6 3389.1 4352.3 4464.8 4508.0 4597.5 85787.0 94518.0 112499.0 117480.0 120420.0 101709.0 103632.0 105100.0 105205.0 105456.5 66312.0 93011.5 94229.1 94477.5 94601.0 8813.5 8948.6 9042.2 9139.7 9249.0 40346.8 44212.1 45815.5 46108.5 49240.5 58986.7 72022.0 74788.6 75924.2 76894.6 11861.0 12095.3 12144.5 12287.2 12456.0 11631.5 13765.2 13979.9 14136.9 14220.0 10596.5 12254.3 12448.6 12778.6 13062.3 12250.5 12256.9 12314.4 12370.8 12585.4 10404.5 10543.3 12461.3 12576.0 13167.7 9737.4 9917.4 10185.4 10188.9 10488.9 10222.2 10682.7 10797.0 10850.5 10864.6 12:55:45 INFO - PROCESS | 1918 | -------- Summary: end -------- 12:55:45 INFO - PROCESS | 1918 | 12:55:46 INFO - TEST-INFO | 1918: exit 0 12:55:46 INFO - TEST-OK | dromaeo_css | took 1117157ms 12:55:46 INFO - TEST-START | kraken 12:55:46 INFO - operating with platform_type : mac_ 12:55:46 INFO - Initialising browser for kraken test... 12:55:46 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpEigmNy/profile http://localhost:52400/getInfo.html) 12:55:49 INFO - PROCESS | 1923 | 2016-11-22 12:55:49.641 plugin-container[1924:18769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 12:55:49 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:55:49 INFO - PROCESS | 1923 | 2016-11-22 12:55:49.646 plugin-container[1924:18769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 12:55:49 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:55:49 INFO - PROCESS | 1923 | 2016-11-22 12:55:49.647 plugin-container[1924:18769] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:55:49 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 12:55:49 INFO - PROCESS | 1923 | colorDepth:24 12:55:49 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 12:55:49 INFO - PROCESS | 1923 | __metrics 12:55:49 ERROR - PROCESS | 1923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 12:55:50 INFO - TEST-INFO | 1923: exit 0 12:55:50 INFO - Browser initialized. 12:55:50 INFO - Running cycle 1/1 for kraken test... 12:55:50 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpEigmNy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:55:53 INFO - PROCESS | 1925 | 2016-11-22 12:55:53.431 plugin-container[1926:18892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 12:55:53 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:55:53 INFO - PROCESS | 1925 | 2016-11-22 12:55:53.436 plugin-container[1926:18892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 12:55:53 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:55:53 INFO - PROCESS | 1925 | 2016-11-22 12:55:53.436 plugin-container[1926:18892] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:56:03 INFO - PROCESS | 1925 | RSS: Main: 241373184 12:56:03 INFO - PROCESS | 1925 | 12:56:06 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52400/tests/kraken/driver.html?testName=audio-beat-detection) 12:56:07 INFO - PROCESS | 1925 | RSS: Main: 253415424 12:56:07 INFO - PROCESS | 1925 | 12:56:09 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52400/tests/kraken/driver.html?testName=audio-dft) 12:56:10 INFO - PROCESS | 1925 | RSS: Main: 261074944 12:56:10 INFO - PROCESS | 1925 | 12:56:13 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52400/tests/kraken/driver.html?testName=audio-fft) 12:56:14 INFO - PROCESS | 1925 | RSS: Main: 263970816 12:56:14 INFO - PROCESS | 1925 | 12:56:16 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52400/tests/kraken/driver.html?testName=audio-oscillator) 12:56:17 INFO - PROCESS | 1925 | RSS: Main: 266100736 12:56:17 INFO - PROCESS | 1925 | 12:56:19 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52400/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:56:19 INFO - PROCESS | 1925 | RSS: Main: 266772480 12:56:19 INFO - PROCESS | 1925 | 12:56:22 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52400/tests/kraken/driver.html?testName=imaging-darkroom) 12:56:23 INFO - PROCESS | 1925 | RSS: Main: 272326656 12:56:23 INFO - PROCESS | 1925 | 12:56:26 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52400/tests/kraken/driver.html?testName=imaging-desaturate) 12:56:27 INFO - PROCESS | 1925 | RSS: Main: 271831040 12:56:27 INFO - PROCESS | 1925 | 12:56:30 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52400/tests/kraken/driver.html?testName=json-parse-financial) 12:56:30 INFO - PROCESS | 1925 | RSS: Main: 271867904 12:56:30 INFO - PROCESS | 1925 | 12:56:32 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52400/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:56:33 INFO - PROCESS | 1925 | RSS: Main: 271212544 12:56:33 INFO - PROCESS | 1925 | 12:56:35 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:56:35 INFO - PROCESS | 1925 | RSS: Main: 271257600 12:56:35 INFO - PROCESS | 1925 | 12:56:37 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:56:38 INFO - PROCESS | 1925 | RSS: Main: 271286272 12:56:38 INFO - PROCESS | 1925 | 12:56:41 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:56:42 INFO - PROCESS | 1925 | RSS: Main: 271470592 12:56:42 INFO - PROCESS | 1925 | 12:56:45 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:56:46 INFO - PROCESS | 1925 | RSS: Main: 271335424 12:56:46 INFO - PROCESS | 1925 | 12:56:47 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52400/tests/kraken/driver.html?testName=ai-astar) 12:56:48 INFO - PROCESS | 1925 | RSS: Main: 273252352 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | __start_tp_report 12:56:48 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 12:56:48 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 12:56:48 INFO - PROCESS | 1925 | |i|pagename|runs| 12:56:48 INFO - PROCESS | 1925 | |0;ai-astar;110;119;119;124;119;121;128;122;129;121 12:56:48 INFO - PROCESS | 1925 | |1;audio-beat-detection;155;117;116;155;144;147;118;119;163;144 12:56:48 INFO - PROCESS | 1925 | |2;audio-dft;208;216;208;201;202;202;258;285;200;199 12:56:48 INFO - PROCESS | 1925 | |3;audio-fft;81;60;57;85;63;63;77;84;71;84 12:56:48 INFO - PROCESS | 1925 | |4;audio-oscillator;87;73;91;73;73;69;86;72;72;104 12:56:48 INFO - PROCESS | 1925 | |5;imaging-gaussian-blur;110;87;91;100;89;133;139;89;89;109 12:56:48 INFO - PROCESS | 1925 | |6;imaging-darkroom;96;103;152;120;97;103;138;109;151;112 12:56:48 INFO - PROCESS | 1925 | |7;imaging-desaturate;110;93;93;119;113;94;92;93;92;92 12:56:48 INFO - PROCESS | 1925 | |8;json-parse-financial;58;58;57;58;59;59;59;58;59;58 12:56:48 INFO - PROCESS | 1925 | |9;json-stringify-tinderbox;74;74;73;88;79;71;67;71;76;77 12:56:48 INFO - PROCESS | 1925 | |10;stanford-crypto-aes;92;96;93;92;92;89;100;115;114;82 12:56:48 INFO - PROCESS | 1925 | |11;stanford-crypto-ccm;151;156;168;146;165;160;169;166;271;163 12:56:48 INFO - PROCESS | 1925 | |12;stanford-crypto-pbkdf2;179;178;170;178;178;177;178;175;175;181 12:56:48 INFO - PROCESS | 1925 | |13;stanford-crypto-sha256-iterative;72;69;70;70;72;66;64;68;70;70 12:56:48 INFO - PROCESS | 1925 | __end_tp_report 12:56:48 INFO - PROCESS | 1925 | __start_cc_report 12:56:48 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,966 12:56:48 INFO - PROCESS | 1925 | __end_cc_report 12:56:48 INFO - PROCESS | 1925 | __startTimestamp1479848208031__endTimestamp 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | ------- Summary: start ------- 12:56:48 INFO - PROCESS | 1925 | Number of tests: 14 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#0] ai-astar Cycles:10 Average:121.20 Median:121.50 stddev:5.33 (4.4%) stddev-sans-first:3.81 12:56:48 INFO - PROCESS | 1925 | Values: 110.0 119.0 119.0 124.0 119.0 121.0 128.0 122.0 129.0 121.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:145.50 stddev:18.38 (12.6%) stddev-sans-first:18.41 12:56:48 INFO - PROCESS | 1925 | Values: 155.0 117.0 116.0 155.0 144.0 147.0 118.0 119.0 163.0 144.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#2] audio-dft Cycles:10 Average:217.90 Median:208.00 stddev:29.40 (14.1%) stddev-sans-first:30.96 12:56:48 INFO - PROCESS | 1925 | Values: 208.0 216.0 208.0 201.0 202.0 202.0 258.0 285.0 200.0 199.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#3] audio-fft Cycles:10 Average:72.50 Median:79.00 stddev:11.02 (13.9%) stddev-sans-first:11.25 12:56:48 INFO - PROCESS | 1925 | Values: 81.0 60.0 57.0 85.0 63.0 63.0 77.0 84.0 71.0 84.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:73.00 stddev:11.44 (15.7%) stddev-sans-first:11.85 12:56:48 INFO - PROCESS | 1925 | Values: 87.0 73.0 91.0 73.0 73.0 69.0 86.0 72.0 72.0 104.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#5] imaging-gaussian-blur Cycles:10 Average:103.60 Median:88.00 stddev:19.07 (21.7%) stddev-sans-first:20.09 12:56:48 INFO - PROCESS | 1925 | Values: 110.0 87.0 91.0 100.0 89.0 133.0 139.0 89.0 89.0 109.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#6] imaging-darkroom Cycles:10 Average:118.10 Median:144.50 stddev:21.45 (14.8%) stddev-sans-first:21.21 12:56:48 INFO - PROCESS | 1925 | Values: 96.0 103.0 152.0 120.0 97.0 103.0 138.0 109.0 151.0 112.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:92.50 stddev:10.52 (11.4%) stddev-sans-first:10.40 12:56:48 INFO - PROCESS | 1925 | Values: 110.0 93.0 93.0 119.0 113.0 94.0 92.0 93.0 92.0 92.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 12:56:48 INFO - PROCESS | 1925 | Values: 58.0 58.0 57.0 58.0 59.0 59.0 59.0 58.0 59.0 58.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:75.00 stddev:5.70 (7.6%) stddev-sans-first:6.03 12:56:48 INFO - PROCESS | 1925 | Values: 74.0 74.0 73.0 88.0 79.0 71.0 67.0 71.0 76.0 77.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:92.00 stddev:10.54 (11.5%) stddev-sans-first:11.06 12:56:48 INFO - PROCESS | 1925 | Values: 92.0 96.0 93.0 92.0 92.0 89.0 100.0 115.0 114.0 82.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#11] stanford-crypto-ccm Cycles:10 Average:171.50 Median:165.50 stddev:35.76 (21.6%) stddev-sans-first:37.15 12:56:48 INFO - PROCESS | 1925 | Values: 151.0 156.0 168.0 146.0 165.0 160.0 169.0 166.0 271.0 163.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:178.00 stddev:3.00 (1.7%) stddev-sans-first:3.08 12:56:48 INFO - PROCESS | 1925 | Values: 179.0 178.0 170.0 178.0 178.0 177.0 178.0 175.0 175.0 181.0 12:56:48 INFO - PROCESS | 1925 | 12:56:48 INFO - PROCESS | 1925 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:2.51 (3.6%) stddev-sans-first:2.44 12:56:48 INFO - PROCESS | 1925 | Values: 72.0 69.0 70.0 70.0 72.0 66.0 64.0 68.0 70.0 70.0 12:56:48 INFO - PROCESS | 1925 | -------- Summary: end -------- 12:56:48 INFO - PROCESS | 1925 | 12:56:49 INFO - TEST-INFO | 1925: exit 0 12:56:49 INFO - TEST-OK | kraken | took 62559ms 12:56:49 INFO - SUITE-END | took 1179s 12:56:49 INFO - Completed test suite (00:19:40) 12:56:49 INFO - javascript benchmark 12:56:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7040.011158900309, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.992902129362, 3399.4560870260734, 3403.3193361327735, 3404.200012996945, 3418.2138108235113, 4588.898664320555, 5578.553715702744, 5623.156526521024, 5649.576281778867, 5735.942640573593, 5638.379778224392, 5723.112917497784, 5753.913691294634, 5816.040353341694, 5861.824145275638, 4053.97973010136, 4181.498220213579, 4981.427135879384, 5185.922211166836, 5331.147016477365, 5148.459411761767, 5300.973495132519, 5320.9467905320835, 5364.409914909357, 5380.542653874425, 5170.9741451292875, 5312.814051508218, 5325.627206095575, 5326.7070311132875, 5344.7862085516535, 4009.278329900617, 5140.228965655144, 5160.122779127557, 5231.136862417697, 5252.343457067866, 4083.183363327317, 4476.865694029184, 5052.216906379517, 5199.66202196856, 5218.452062533455, 5567.164925261202, 5715.771369145229, 5718.0, 5749.59752817303, 5755.280589926259, 5066.847994560169, 5191.896162076738, 5946.5540084493405, 6161.9691901540655, 6218.751249949996, 4854.975725121387, 5942.900563395786, 6118.131909153803, 6158.7228574714245, 6172.537059720494, 5029.924551131736, 5844.532437404998, 5864.237649105632, 5867.73595188209, 5871.442212989759, 6016.548758843104, 6216.813495595139, 6326.620402775849, 6332.240131184288, 6357.841053973688, 5454.427285135083, 6099.146119543268, 6115.755369785248, 6121.296050954197, 6130.275842464065, 6098.207233059674, 6216.471599913967, 6278.15244941927, 6297.874042519124, 6303.810885673437, 6042.305134909541, 6118.1434599156155, 6156.015037593964, 6169.81490555284, 6194.628322300677, 6242.875142497215, 6252.593581417284, 6261.123442718023, 6272.0, 6287.088372186007, 4862.610991120702, 4881.8535443936025, 4903.583195428429, 4996.850094497171, 5018.272350509155, 4368.60682538573, 4404.757738324359, 4429.867103986886, 4457.197704413236, 4466.553344665507, 5724.713764311768, 5881.411858814085, 5895.086261629454, 5913.29040515141, 5951.256092988377, 5186.507281808223, 6247.531435142382, 6253.374662533801, 6257.56197066201, 6280.057991301342, 5669.177969194444, 5693.886122277614, 5717.028105222123, 5779.3642699303045, 5880.617759845596, 5325.520703136736, 5379.919301210485, 5388.757505912244, 5420.34955805298, 5463.754131064112, 6349.492040636738, 6364.395382438661, 6368.395002474757, 6371.331010243857, 6386.233651961795, 6250.906236406367, 6252.468540174136, 6260.937390626035, 6263.311035786151, 6266.091416744455, 6190.81427557174, 6224.408681175281, 6243.500519958483, 6249.218847644044, 6376.649284289409, 6152.98475751496, 6204.286507051746, 6208.317085120543, 6225.62646241227, 6248.9062664059165, 6177.907331390123, 6229.909765790878, 6232.096346029891, 6245.094461303176, 6247.000479923372, 5919.200907877382, 5946.702664866826, 5990.580659353804, 6123.693815309306, 6138.447539721446, 6045.093236014633, 6055.0, 6061.545384096122, 6085.904537183173, 6105.725242364016, 4329.826806927687, 5419.753456705059, 5951.137085122719, 5991.700414979146, 6062.242219722535, 5189.910118874927, 6073.605215660968, 6117.143599895929, 6155.138280640804, 6157.692115394123, 5948.762049517971, 5964.731587078505, 5988.191594134736, 6004.969975150097, 6015.187949626745], "value": 5619.564421822018, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.13581756564537, 505.9873503162421, 512.2521119166015, 519.3819354967588, 532.7496076843887, 29841.552376714368, 39845.804625861274, 39919.600803991954, 39960.60039399609, 39980.60019399812, 40752.592474075325, 41287.38068928968, 41312.0, 41314.17371652565, 41324.79337603308, 9224.169824715775, 9330.626774928995, 9378.249740020816, 9380.671676491324, 9424.623015079389, 9324.25405967521, 9402.576884040234, 9402.62389504419, 9417.340786144972, 9431.858522122173, 8026.678932842679, 9351.76620584484, 9375.10936461035, 9405.623775048989, 9414.952925235399, 9134.0, 9213.2168765655, 9232.676856309998, 9251.444913305224, 9265.814683706321, 8435.493870367798, 8701.216890479824, 9109.223701378234, 9139.040400757958, 9216.723498295061, 38828.41757373641, 38993.80503097466, 39181.608183918084, 39202.60797392018, 39287.0, 2686.6104414859738, 2816.788740844445, 3135.3415782685665, 3186.203449137716, 3187.569678093451, 25487.745122548724, 29853.40293194146, 29952.8502357489, 30294.091177264716, 30720.69279307201, 2884.639420072491, 2937.0748214312425, 2960.3487232808748, 2974.568687540316, 2994.82031078136, 332529.0, 336491.0, 336769.6323036763, 338888.0, 340708.0, 50745.74627126841, 59326.81346373048, 59505.0, 59598.404015959284, 59695.70152149211, 31067.223319416742, 31754.682453175174, 50067.74966125146, 61377.0, 61409.69295153585, 15504.844951550565, 15853.286602102933, 15869.0, 15899.28453219608, 15940.963837350535, 12234.204776689487, 12294.631161065183, 12319.753604927851, 12320.137590368768, 12440.0, 18838.0, 18880.62238755245, 18952.14715337813, 18954.715679264824, 18962.71555926662, 6521.673916304166, 6791.456683465311, 7402.814929626802, 7694.538327700356, 7799.727009554638, 739.5747445219021, 861.7802460372565, 879.5118709116374, 951.4434056077272, 1019.3476175247847, 22162.891855407164, 24466.87766561156, 28433.00484483033, 28643.427131457254, 30263.848680756455, 1011.5650270383659, 1018.0097908588691, 1020.8519764634235, 1022.9846552301722, 1052.710504611238, 11595.478203480863, 12455.626331210078, 13513.52702655422, 15529.679912793712, 15849.920750396173, 127307.0, 176159.0, 176201.0, 177298.0, 181283.0, 55214.0, 55308.0, 55683.721581392645, 55748.44251557432, 55947.72026139925, 1026.8357062869904, 1117.1063149480383, 1248.8001919692806, 1298.571471414431, 1344.2405041151717, 21011.05450254773, 21069.367918962456, 30254.243643909078, 31225.8438707805, 32564.83717581397, 55215.72392138014, 55956.88086238334, 56094.0, 56169.0, 56321.15518267147, 608740.0, 612687.0, 613335.0, 613436.0, 615844.0, 40201.798991004856, 40777.184456311305, 41340.966475838344, 41495.792521037205, 41538.0, 45067.54932450633, 45822.0, 46210.68946552592, 46733.06533869372, 47167.76416117897, 32432.0, 38017.0, 38149.23701526009, 38885.41671874981, 38959.2208155841, 37723.81138094292, 37861.81069094637, 37931.2413751729, 38838.22323553456, 38979.0, 30125.0, 31508.26704531273, 32332.83833580817, 33346.666533334355, 37573.2485350286, 31092.68907310898, 32158.51762223615, 34204.0, 34258.16999980069, 34821.82589087039, 22931.0, 24099.277021689377, 25039.0, 25095.49809003773, 25336.873315633304], "value": 18395.089032972664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.6153123954395, 356.80910712768656, 364.26782167842634, 381.7766606535177, 394.9395872083456, 15700.921495392535, 17887.821121788777, 18473.445796626107, 19486.830790152584, 19672.70490942637, 18687.532811679714, 18883.62232755344, 19014.619707605838, 19043.61912761744, 19219.519512012208, 5289.894202115975, 5378.139497680372, 5937.643741375509, 5950.196723442345, 5968.462838344548, 4998.325226094467, 5577.776888924458, 5787.450192231731, 5806.477417032466, 5875.94233038053, 4928.97535512322, 5808.361080281165, 5819.243498345209, 5828.679422631753, 5843.2403787507565, 5502.4043027521975, 5606.046972014747, 5683.5169010634145, 5709.0, 5709.25779648643, 4955.82654607087, 4974.452810190876, 4990.476000020019, 4997.525235102659, 4998.500149985009, 22726.204582839688, 23132.884335578383, 23860.64209036866, 23913.88043059791, 23975.921083551108, 1871.429214089702, 2397.568437681217, 2423.503181847717, 2986.313147975956, 3111.0822307419367, 14203.147811131263, 14733.484328048467, 14921.850781492154, 15052.32264548098, 15389.461368852037, 1821.5355084453513, 1840.2546968477789, 1845.2065611786927, 1859.8140185981429, 1861.7672790901138, 115156.0, 116273.0, 116645.41677291559, 116659.0, 118979.0, 8179.263866251947, 10206.948965255126, 10236.129928955974, 10687.198460115367, 10897.509612067317, 12933.224006559449, 12960.28718420477, 12962.935185324202, 12968.740625187444, 13008.54470093561, 6810.284920083419, 7759.961200193963, 8068.0721717002125, 8166.9591652042545, 8223.17768223173, 7380.741674041383, 7895.60521973899, 8048.315893149031, 8087.636056377477, 8257.133001034925, 5229.50319719626, 5254.737263136828, 6194.81415557534, 6331.366863313632, 6410.358964103552, 1796.568823482355, 1830.844378227839, 1833.220881125543, 1834.596388794463, 1842.263094762106, 880.4101252161067, 909.6816114359921, 912.4388501071926, 952.6856137474617, 975.9853602195973, 15981.680366392608, 20603.690944635844, 20740.688889666668, 20984.160633574793, 21106.366808995754, 818.705266104202, 854.3421565394611, 926.7636752628172, 942.2885721280527, 986.3194395866828, 8311.709090181936, 9364.531773411301, 10268.383896966057, 10860.076893463985, 11038.613648522263, 578605.1069744624, 578626.0, 585088.0, 597333.0, 597483.0, 111897.0, 113733.0, 114879.0, 115923.0, 119859.0, 957.8467445208734, 964.8118616869783, 993.5826952679892, 995.3381001633977, 1005.070309963281, 17420.390286339916, 18300.542486437946, 18357.54106147357, 18385.0, 18407.447776566725, 49625.75187124186, 49914.01203831539, 51038.74480627573, 51487.45537633877, 51780.96438071142, 48928.75535622299, 65499.0, 71325.6433717828, 73073.6346318265, 79735.60132199535, 8902.42134261271, 11347.943260283646, 11596.188266821242, 11738.060955123741, 12082.939585302018, 10283.382997020202, 11413.543458261576, 13138.474461021453, 13209.537666181637, 13410.865891340962, 11312.27332040057, 11477.885221148015, 11542.769144616892, 11682.415879206175, 11757.059435245332, 9792.657256995972, 10529.36823790575, 10973.177011723992, 11412.942935285271, 11435.313881167156, 11032.944835276094, 11428.914253145938, 11585.6524304271, 11587.246828956091, 11823.940880295888, 10111.59553617847, 11665.300081995107, 11672.299662020307, 12048.277103373826, 12055.97524210452, 11328.660140195807, 11568.107973006749, 11568.47941842636, 11592.942035289769, 11660.825087623523], "value": 9554.574511695355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1189.1022278179973, 1451.65886016786, 1609.1793185475412, 1750.894946303221, 1765.3909401256562, 7591.772246832604, 7814.765557033284, 8046.5574393408415, 8502.744917652473, 8511.361647876418, 8008.399370047241, 8293.502389856598, 8314.792130196749, 8398.49609023459, 8448.11294814045, 1947.7273181754565, 2268.1381075191402, 2297.655351697242, 2302.355340504653, 2311.144876395736, 2242.473018840571, 2278.5784629843533, 2292.3695983604475, 2295.0, 2302.0216408026604, 2281.5094754627753, 2282.417983414227, 2304.6427803690453, 2318.5247024359965, 2320.037184568402, 2262.162999690109, 2266.841321107523, 2293.0713061210154, 2293.4839661076126, 2294.8737819419925, 2305.1931823861682, 2324.1284518305633, 2336.0655737704883, 2336.836421450499, 2338.6375111857687, 8328.33373330132, 8355.0, 8403.495790252604, 8409.663613455516, 8423.199796019368, 5047.410065829252, 5205.661631993946, 5208.166693329087, 5221.138512145491, 5229.980153869997, 3002.6546947101147, 3134.200778801414, 3401.1667141550233, 3409.7272218222483, 3423.9486407703903, 949.3781573069581, 962.5379817687549, 992.6426486464867, 999.5801763259376, 1000.6747806962693, 9739.26955478342, 10832.72918177044, 10945.01494865466, 10954.123670106372, 10981.835272470918, 4567.725936443824, 4721.268203428474, 4734.763261836894, 4743.762811859393, 4775.594074503637, 7710.53736775784, 8630.568471576396, 8743.0, 8786.472811631322, 8803.691870784492, 5131.409887862869, 5134.922976155361, 5137.51193636604, 5139.922901156486, 5188.870278243074, 4600.332951721982, 4737.123632128068, 4792.113459010095, 4826.300186472942, 4845.781939812717, 3193.2495863472063, 3584.086058055231, 3667.0832291927018, 3696.057505336122, 3735.495708079429, 1150.1948635954866, 1157.1379322404862, 1164.0512981919708, 1169.3276366089533, 1174.9060075193963, 738.5494848142629, 880.1198801198801, 884.8805411269525, 897.5467388968659, 902.8555431131119, 5424.349078110573, 5507.201455788888, 5691.544676425876, 5703.657780533182, 5871.677057761779, 961.624966263158, 963.643451922779, 973.5667627905656, 983.537737263485, 1062.1396668698378, 3523.5947865995226, 3639.090227443139, 3641.344557979536, 3711.0, 3712.424574191005, 9966.2525310602, 10303.278770486144, 10303.536340864679, 10317.896821031694, 10357.896421035844, 9571.856422153673, 10092.899071009197, 10277.588896444058, 10281.434521101412, 10395.68812935598, 7667.846643067108, 7869.331106855867, 7895.407844411692, 7902.762917112495, 7941.126476087626, 4375.365571992107, 5010.69935803853, 5603.719814009365, 5607.775688972395, 5704.2014118024335, 9034.548272586213, 9127.77180570491, 9225.72322830316, 9258.629654813732, 9386.67146649864, 8229.753107406787, 9354.672586459441, 9384.436933783722, 9401.576929038072, 9415.670451534163, 6256.562040657111, 7240.239774823778, 7241.348278654947, 7259.2377800332315, 7268.273172682901, 7786.805329866798, 8010.599470026592, 8032.397570182143, 8114.350851931949, 8166.469179503313, 6241.750329986931, 7070.8232294191, 7111.679974401268, 7217.566945983258, 7275.527090739085, 7032.789016329519, 7066.081409416743, 7080.4335653148655, 7096.148462184571, 7096.680649370687, 5720.256366672306, 6631.502637302126, 6651.301613330531, 6657.434118099822, 6724.663766811737, 4656.580907718321, 4664.696794708333, 4702.200625893674, 4748.0978614063215, 4802.567768900815, 4665.113628410591, 4786.760661966818, 4802.327674125555, 4832.782524786323, 4915.95084049155], "value": 4357.369928772507, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.3149298022494, 298.08487942018, 306.904859493557, 308.6697233959664, 313.8117129722167, 5353.116735738598, 5408.2698835657175, 5410.161424979125, 5432.266644003058, 5448.918266226, 4331.7400955942585, 4981.651284410093, 5219.973900130494, 5277.788888444457, 5310.654807437514, 3554.715622750187, 3871.8838434846857, 3884.950314003501, 4015.698822588303, 4050.8784736457956, 3226.370857682753, 3362.9406736877927, 3481.56480439945, 4122.670186385097, 4129.967508122969, 3867.38121900496, 4081.857135000276, 4086.504844670868, 4098.774567398792, 4103.343465045593, 3591.586967498745, 3597.4963505109304, 3743.3636281832155, 3903.0, 4069.47096877407, 2914.6793852676187, 3252.8536215870345, 3329.9344209852857, 3522.9119272018147, 3542.0, 5055.469175736532, 5095.439501654815, 5130.333056702642, 5144.43411224765, 5173.560247378978, 4335.739855608674, 4546.640477702461, 4573.062522182944, 4613.538646135393, 4667.486576476585, 5441.571809759826, 5494.5054945054735, 5497.230387745719, 5546.1143211308345, 5643.472829481574, 3578.856845726194, 3581.5881173665102, 3753.962460375416, 3919.510061242345, 3923.391874259466, 4923.4091908970795, 5147.91893702323, 5684.2326285951285, 5959.433853783884, 5986.610870293417, 3320.5517255170726, 3322.368749937504, 3880.825362858678, 3917.7845218513257, 3919.451276821247, 4015.1128969384345, 4015.5382131055303, 4017.216642754635, 4035.5157381114454, 4070.1859628074503, 4288.5711428857485, 4578.649298456963, 4621.30680397943, 4664.580187783048, 4692.155412025868, 4460.553944605578, 4743.079052983548, 4761.35721677576, 5401.838604700009, 5658.321001479849, 1006.0492834271544, 1129.8532787703266, 1258.182181581979, 1266.7213213093105, 1285.4729560879994, 825.529448214524, 830.7756905635565, 860.8450478913855, 875.4965894610497, 883.7715575350268, 4409.470863496401, 4487.304467807495, 4815.807367705253, 5351.411344752074, 5362.93526992103, 4685.367475390779, 5806.854828629233, 5841.757824407495, 5884.764609415661, 5901.76392944286, 4455.532169122195, 4460.977695111504, 4707.011527579247, 4922.50774922512, 5023.949760502422, 2485.403503159265, 2591.481703659276, 2728.6452761141322, 2733.6309598204384, 2916.8395738234176, 4497.437820272466, 4982.476839931827, 5171.595682511785, 5736.42635736423, 5746.195532625352, 4055.148418832019, 5196.506331898464, 5309.425103641239, 5425.849225342616, 5657.405972372924, 4985.875353116201, 5041.6470847040355, 5106.974465127725, 5656.632318899258, 5696.0095949626775, 5069.518395752398, 5457.908418316432, 5612.186232996274, 5648.406917273627, 5668.971655141698, 4563.977180114078, 5292.7353632319, 5410.3768869338255, 5478.397376288525, 5770.32660528442, 6075.422834830684, 6130.877382452415, 6158.445739883432, 6176.938230617637, 6177.336111235988, 3769.1896242308035, 3957.881263562098, 3962.445257663987, 3970.325044742343, 3986.242613903349, 4923.769057735566, 4949.777260023236, 5237.973810130925, 5880.764769409176, 5920.526357891272, 4739.857804265877, 5171.81898633546, 5499.230107784833, 5669.688167150846, 5697.772089116389, 4914.606831453403, 5161.2193793141305, 5686.886262274814, 5694.579803583543, 5700.486956173964, 5311.814086506954, 5450.737943753298, 5670.0, 5679.971600142138, 5719.942800572107, 4315.417418648442, 5011.949880501148, 5373.7850485980125, 5579.46995035471, 5697.259356283822, 4797.688150270221, 4837.032593481388, 5249.055170069424, 5270.947290527046, 5590.692511911884], "value": 3992.0753633351765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.9734010639575, 699.805388401945, 722.1911459165735, 746.3059354800041, 766.0003695177791, 13442.932785336072, 14277.571672849805, 14301.284935753196, 14347.354369053392, 14365.569032929016, 12731.2997785122, 12826.973842092633, 13582.388792504336, 14156.575302740908, 14297.428102875872, 804.5816175588704, 815.4658698552457, 824.2046425199682, 826.9952008309903, 827.0, 645.3643161485955, 816.9714060007901, 819.9180081991813, 821.3593397150232, 821.3798582070558, 697.3061803505519, 817.6892780743339, 850.3962186847345, 859.2224037246302, 866.5710473315718, 856.3149480415705, 856.6273670953155, 856.8671855862351, 858.4034096303072, 859.299670768328, 632.3676323676324, 635.6122765113278, 636.3572791480592, 637.9553431259814, 638.1066506890344, 11601.187916845824, 13288.933555332258, 13960.860391396058, 13979.720405591934, 13984.440622375092, 9853.113219810251, 11950.880491195063, 12214.755704885942, 12267.202631828992, 12268.44791984363, 11852.644420667393, 11871.169018168732, 11968.760624787456, 11984.580539681156, 12399.876001240053, 3691.3171063353366, 4251.617354438115, 4354.738715677038, 4417.911641767147, 4455.532169122261, 84328.57835710782, 86363.13636863614, 87349.05632249039, 91155.0, 92997.53501232518, 9932.304738668225, 9968.850467242997, 10020.24848136378, 10062.295639305325, 10107.595696172219, 18923.716144257847, 18957.715634265496, 19042.904785475985, 19140.712889306673, 19244.518887027938, 7266.818329541698, 7271.600061996641, 7292.234315396807, 7409.851802963911, 7471.589062601501, 4090.652294554937, 4154.065335299533, 4160.9375859362135, 4190.664746820247, 4193.874183774492, 2838.389746204576, 3107.300857307088, 3198.616166060088, 3213.5661685672603, 3636.6726994570085, 605.3371558143914, 653.8953767397289, 657.7336178847575, 662.8508585568209, 667.9599224046573, 554.877926856091, 567.4552429667565, 568.4287291272327, 632.5003247434568, 661.4014317043084, 7562.357199638092, 8158.510489370538, 8775.517346546001, 8778.91221087781, 8810.32603805275, 552.4585905812327, 590.6249531547412, 616.9938300617026, 658.6805399381296, 663.6482664187988, 3389.1357703785375, 4352.325389564622, 4464.785354544722, 4507.965425400228, 4597.540245975442, 85787.0, 94518.0, 112499.0, 117480.0, 120420.0, 101708.98291016996, 103631.96368036223, 105100.0, 105205.0, 105456.47271763592, 66312.0, 93011.53494232486, 94229.0577094234, 94477.5276123615, 94601.0, 8813.515256660945, 8948.642054317754, 9042.186203241477, 9139.725808225763, 9248.98261191282, 40346.79826600848, 44212.11575768531, 45815.54184458113, 46108.53891461177, 49240.50759492359, 58986.705066474395, 72022.0, 74788.62605686937, 75924.24075759172, 76894.615526922, 11861.0, 12095.334756588472, 12144.453499592717, 12287.201331913397, 12456.0, 11631.53473861036, 13765.242911639956, 13979.930100349433, 14136.929315353358, 14220.0, 10596.523156458132, 12254.326012069065, 12448.564300249447, 12778.61664150077, 13062.34688265602, 12250.4709036365, 12256.877431225574, 12314.445849936596, 12370.81443778362, 12585.370731463207, 10404.531796069346, 10543.31468454548, 12461.252324860538, 12576.0, 13167.67080822987, 9737.415755054719, 9917.355371900803, 10185.439800811026, 10188.949055254676, 10488.895111048792, 10222.160566004153, 10682.73293167677, 10797.0, 10850.45747712627, 10864.619738309122], "value": 7085.248256818565, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 119.0, 124.0, 119.0, 121.0, 128.0, 122.0, 129.0, 121.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 117.0, 116.0, 155.0, 144.0, 147.0, 118.0, 119.0, 163.0, 144.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 216.0, 208.0, 201.0, 202.0, 202.0, 258.0, 285.0, 200.0, 199.0], "value": 217.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 57.0, 85.0, 63.0, 63.0, 77.0, 84.0, 71.0, 84.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 91.0, 73.0, 73.0, 69.0, 86.0, 72.0, 72.0, 104.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 87.0, 91.0, 100.0, 89.0, 133.0, 139.0, 89.0, 89.0, 109.0], "value": 103.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 103.0, 152.0, 120.0, 97.0, 103.0, 138.0, 109.0, 151.0, 112.0], "value": 118.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 93.0, 93.0, 119.0, 113.0, 94.0, 92.0, 93.0, 92.0, 92.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 88.0, 79.0, 71.0, 67.0, 71.0, 76.0, 77.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 93.0, 92.0, 92.0, 89.0, 100.0, 115.0, 114.0, 82.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 156.0, 168.0, 146.0, 165.0, 160.0, 169.0, 166.0, 271.0, 163.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 178.0, 170.0, 178.0, 178.0, 177.0, 178.0, 175.0, 175.0, 181.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 70.0, 70.0, 72.0, 66.0, 64.0, 68.0, 70.0, 70.0], "value": 69.1, "unit": "score"}]}]} 12:56:49 INFO - Return code: 0 12:56:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:56:49 ERROR - # TBPL SUCCESS # 12:56:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:56:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:56:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:56:49 INFO - 2016-11-22 12:56:49.892 screenresolution[1930:19308] starting screenresolution argv=screenresolution get 12:56:49 INFO - 2016-11-22 12:56:49.894 screenresolution[1930:19308] Display 0: 1600x1200x32@50 12:56:49 INFO - 2016-11-22 12:56:49.900 screenresolution[1931:19311] starting screenresolution argv=screenresolution list 12:56:49 INFO - Available Modes on Display 0 12:56:49 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 12:56:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:56:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:56:49 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 12:56:49 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 12:56:50 INFO - Graphics/Displays: 12:56:50 INFO - Intel Iris: 12:56:50 INFO - Chipset Model: Intel Iris 12:56:50 INFO - Type: GPU 12:56:50 INFO - Bus: Built-In 12:56:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:56:50 INFO - Vendor: Intel (0x8086) 12:56:50 INFO - Device ID: 0x0a2e 12:56:50 INFO - Revision ID: 0x0009 12:56:50 INFO - Displays: 12:56:50 INFO - Display: 12:56:50 INFO - Resolution: 1600 x 1200 @ 50 Hz 12:56:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:56:50 INFO - Display Serial Number: 1600x1200 50 12:56:50 INFO - Main Display: Yes 12:56:50 INFO - Mirror: Off 12:56:50 INFO - Online: Yes 12:56:50 INFO - Rotation: Supported 12:56:50 INFO - Return code: 0 12:56:50 INFO - Running post-action listener: _resource_record_post_action 12:56:50 INFO - [mozharness: 2016-11-22 20:56:50.137429Z] Finished run-tests step (success) 12:56:50 INFO - Running post-run listener: _resource_record_post_run 12:56:50 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 581632; Write bytes: 696400896; Read time: 7; Write time: 1899 12:56:50 INFO - TinderboxPrint: CPU usage
54.4% 12:56:50 INFO - TinderboxPrint: I/O read bytes / time
581,632 / 7 12:56:50 INFO - TinderboxPrint: I/O write bytes / time
696,400,896 / 1,899 12:56:50 INFO - TinderboxPrint: CPU idle
2,214.0 (45.6%) 12:56:50 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 12:56:50 INFO - TinderboxPrint: CPU user
1,660.3 (34.2%) 12:56:50 INFO - TinderboxPrint: Swap in / out
382,857,216 / 0 12:56:50 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 361992704; Write bytes: 357638656; Read time: 27434; Write time: 660 12:56:50 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 581632; Write bytes: 309586432; Read time: 7; Write time: 1167 12:56:50 INFO - Running post-run listener: _upload_blobber_files 12:56:50 INFO - Blob upload gear active. 12:56:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:56:50 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 12:56:50 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:56:50 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:56:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:56:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:56:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:56:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:56:51 INFO - (blobuploader) - INFO - Done attempting. 12:56:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:56:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:56:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:56:52 INFO - (blobuploader) - INFO - Done attempting. 12:56:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:56:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:56:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:56:55 INFO - (blobuploader) - INFO - Done attempting. 12:56:55 INFO - (blobuploader) - INFO - Iteration through files over. 12:56:55 INFO - Return code: 0 12:56:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:56:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:56:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3a7a12f0a43a691e3a0162156ed85bb09f16665ee2064b56b09516ec22f753ff7b6a1223cc68e8c2cbcb0f77ca98e6c3ce838ea2440e95436f2b84ea7e107e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/054699a4733ed1f44b35a6d2523fee363ef21d1193a94888fb7962f7ff2ecfc38c251910977063781440a038fac26a220b0d686483aacfbf9378c309cf39a6fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9d37ff0fa0d0f97d53c11cc844bb7414891e225f8fc585d82254b181b5b087ad06c161fe86d71fbfa33d9f31c41dd2e070da48215cb968e26137aa4be36bbea3"} 12:56:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:56:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:56:55 INFO - Contents: 12:56:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3a7a12f0a43a691e3a0162156ed85bb09f16665ee2064b56b09516ec22f753ff7b6a1223cc68e8c2cbcb0f77ca98e6c3ce838ea2440e95436f2b84ea7e107e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/054699a4733ed1f44b35a6d2523fee363ef21d1193a94888fb7962f7ff2ecfc38c251910977063781440a038fac26a220b0d686483aacfbf9378c309cf39a6fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9d37ff0fa0d0f97d53c11cc844bb7414891e225f8fc585d82254b181b5b087ad06c161fe86d71fbfa33d9f31c41dd2e070da48215cb968e26137aa4be36bbea3"} 12:56:55 INFO - Running post-run listener: copy_logs_to_upload_dir 12:56:55 INFO - Copying logs to upload dir... 12:56:55 INFO - mkdir: /builds/slave/test/build/upload/logs 12:56:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.081465 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-22 12:56:55.152080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 12:56:55.155410) ========= 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.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/Cedar/sha512/3a7a12f0a43a691e3a0162156ed85bb09f16665ee2064b56b09516ec22f753ff7b6a1223cc68e8c2cbcb0f77ca98e6c3ce838ea2440e95436f2b84ea7e107e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/054699a4733ed1f44b35a6d2523fee363ef21d1193a94888fb7962f7ff2ecfc38c251910977063781440a038fac26a220b0d686483aacfbf9378c309cf39a6fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9d37ff0fa0d0f97d53c11cc844bb7414891e225f8fc585d82254b181b5b087ad06c161fe86d71fbfa33d9f31c41dd2e070da48215cb968e26137aa4be36bbea3"} build_url:https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/EG5F_pXXTKSphJKUkLRujA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3a7a12f0a43a691e3a0162156ed85bb09f16665ee2064b56b09516ec22f753ff7b6a1223cc68e8c2cbcb0f77ca98e6c3ce838ea2440e95436f2b84ea7e107e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/054699a4733ed1f44b35a6d2523fee363ef21d1193a94888fb7962f7ff2ecfc38c251910977063781440a038fac26a220b0d686483aacfbf9378c309cf39a6fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9d37ff0fa0d0f97d53c11cc844bb7414891e225f8fc585d82254b181b5b087ad06c161fe86d71fbfa33d9f31c41dd2e070da48215cb968e26137aa4be36bbea3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 12:56:55.194307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:56:55.194758) ========= 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.k9MT6b1uTE/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.EcASGhfDx2/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005070 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:56:55.247512) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 12:56:55.248282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 12:56:55.248930) ========= ========= Total master_lag: 0.21 =========