builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0146 starttime: 1485316095.92 results: success (0) buildid: 20170124172347 builduid: 25e252b2367e4783926bb920e803fdbe revision: 4663bc61d861d9a967db3fb7d59f08c13c740ca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:15.922509) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:15.923210) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:15.923645) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:15.951997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:15.952345) ========= 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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-24 19:48:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2017-01-24 19:48:16 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:16.137335) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:16.137654) ========= 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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.027375 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:16.187115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 19:48:16.187484) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4663bc61d861d9a967db3fb7d59f08c13c740ca5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4663bc61d861d9a967db3fb7d59f08c13c740ca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-24 19:48:16,291 truncating revision to first 12 chars 2017-01-24 19:48:16,291 Setting DEBUG logging. 2017-01-24 19:48:16,291 attempt 1/10 2017-01-24 19:48:16,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4663bc61d861?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-24 19:48:17,360 unpacking tar archive at: mozilla-beta-4663bc61d861/testing/mozharness/ program finished with exit code 0 elapsedTime=1.340020 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 19:48:17.551809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:17.552177) ========= script_repo_revision: 4663bc61d861d9a967db3fb7d59f08c13c740ca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:17.552702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:17.553031) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 19:48:17.573312) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-24 19:48:17.573683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:48:17 INFO - MultiFileLogger online at 20170124 19:48:17 in /builds/slave/test 19:48:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:48:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:48:17 INFO - {'append_to_log': False, 19:48:17 INFO - 'base_work_dir': '/builds/slave/test', 19:48:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:48:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:48:17 INFO - 'branch': 'Mozilla-Beta', 19:48:17 INFO - 'buildbot_json_path': 'buildprops.json', 19:48:17 INFO - 'config_files': ('talos/mac_config.py',), 19:48:17 INFO - 'default_actions': ('clobber', 19:48:17 INFO - 'read-buildbot-config', 19:48:17 INFO - 'download-and-extract', 19:48:17 INFO - 'populate-webroot', 19:48:17 INFO - 'create-virtualenv', 19:48:17 INFO - 'install', 19:48:17 INFO - 'run-tests'), 19:48:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:48:17 INFO - 'download_minidump_stackwalk': True, 19:48:17 INFO - 'download_symbols': 'ondemand', 19:48:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:48:17 INFO - 'tooltool.py': '/tools/tooltool.py', 19:48:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:48:17 INFO - '/tools/misc-python/virtualenv.py')}, 19:48:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:48:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:48:17 INFO - 'installer_path': 'installer.exe', 19:48:17 INFO - 'log_level': 'info', 19:48:17 INFO - 'log_name': 'talos', 19:48:17 INFO - 'log_to_console': True, 19:48:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:48:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:48:17 INFO - 'opt_config_files': (), 19:48:17 INFO - 'pip_index': False, 19:48:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:48:17 INFO - 'cmd': ('bash', 19:48:17 INFO - '-c', 19:48:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:48:17 INFO - 'enabled': True, 19:48:17 INFO - 'halt_on_failure': False, 19:48:17 INFO - 'name': 'check_screen_resolution'},), 19:48:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:48:17 INFO - 'cmd': ('bash', 19:48:17 INFO - '-c', 19:48:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:48:17 INFO - 'enabled': True, 19:48:17 INFO - 'halt_on_failure': False, 19:48:17 INFO - 'name': 'check_screen_resolution'},), 19:48:17 INFO - 'run_cmd_checks_enabled': True, 19:48:17 INFO - 'sps_profile': False, 19:48:17 INFO - 'sps_profile_interval': 0, 19:48:17 INFO - 'suite': 'dromaeojs-e10s', 19:48:17 INFO - 'system_bits': '32', 19:48:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:48:17 INFO - 'title': 't-yosemite-r7-0146', 19:48:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:48:17 INFO - 'use_talos_json': True, 19:48:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:48:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:48:17 INFO - 'work_dir': 'build'} 19:48:17 INFO - [mozharness: 2017-01-25 03:48:17.788605Z] Running clobber step. 19:48:17 INFO - Running pre-action listener: _resource_record_pre_action 19:48:17 INFO - Running main action method: clobber 19:48:17 INFO - rmtree: /builds/slave/test/build 19:48:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:48:20 INFO - Running post-action listener: _resource_record_post_action 19:48:20 INFO - [mozharness: 2017-01-25 03:48:20.280857Z] Finished clobber step (success) 19:48:20 INFO - [mozharness: 2017-01-25 03:48:20.281075Z] Running read-buildbot-config step. 19:48:20 INFO - Running pre-action listener: _resource_record_pre_action 19:48:20 INFO - Running main action method: read_buildbot_config 19:48:20 INFO - Using buildbot properties: 19:48:20 INFO - { 19:48:20 INFO - "project": "", 19:48:20 INFO - "product": "firefox", 19:48:20 INFO - "script_repo_revision": "production", 19:48:20 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:48:20 INFO - "repository": "", 19:48:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:48:20 INFO - "buildid": "20170124172347", 19:48:20 INFO - "pgo_build": "False", 19:48:20 INFO - "basedir": "/builds/slave/test", 19:48:20 INFO - "buildnumber": 13, 19:48:20 INFO - "slavename": "t-yosemite-r7-0146", 19:48:20 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:48:20 INFO - "platform": "yosemite_r7", 19:48:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:48:20 INFO - "branch": "mozilla-beta", 19:48:20 INFO - "repo_path": "releases/mozilla-beta", 19:48:20 INFO - "revision": "4663bc61d861d9a967db3fb7d59f08c13c740ca5", 19:48:20 INFO - "stage_platform": "macosx64", 19:48:20 INFO - "builduid": "25e252b2367e4783926bb920e803fdbe", 19:48:20 INFO - "slavebuilddir": "test" 19:48:20 INFO - } 19:48:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 19:48:20 INFO - Running post-action listener: _resource_record_post_action 19:48:20 INFO - [mozharness: 2017-01-25 03:48:20.286872Z] Finished read-buildbot-config step (success) 19:48:20 INFO - [mozharness: 2017-01-25 03:48:20.287017Z] Running download-and-extract step. 19:48:20 INFO - Running pre-action listener: _resource_record_pre_action 19:48:20 INFO - Running main action method: download_and_extract 19:48:20 INFO - mkdir: /builds/slave/test/build/tests 19:48: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')]} 19:48:20 INFO - trying https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 19:48:20 INFO - Downloading https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 19:48:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 19:48:26 INFO - Downloaded 1165 bytes. 19:48:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 19:48:26 INFO - Using the following test package requirements: 19:48:26 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 19:48:26 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 19:48:26 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 19:48:26 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:48:26 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 19:48:26 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 19:48:26 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 19:48:26 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 19:48:26 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 19:48:26 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 19:48:26 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 19:48:26 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 19:48:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 19:48:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 19:48:26 INFO - Fetch https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 19:48:26 INFO - Http code: 200 19:48:26 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 19:48:26 INFO - x-amz-cf-id: dlcnsjtnKo0Eg7rkwirm_wz0e_waEsAcfwo4ejNSdipv_OeVid9hJQ== 19:48:26 INFO - x-amz-version-id: 8nhxzSPUqCO8V9OktwDvkFV15xhpry2R 19:48:27 INFO - Expected file size: 16086058 19:48:27 INFO - Obtained file size: 16086058 19:48:33 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:48:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 19:48:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 19:48:33 INFO - Fetch https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 19:48:34 INFO - Http code: 200 19:48:34 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 19:48:34 INFO - x-amz-cf-id: mHvSZIMt51-QVt8osi2tARGrHe60qLfbx9FZ0ofDFZtHx3EzgxlzWg== 19:48:34 INFO - x-amz-version-id: 8nhxzSPUqCO8V9OktwDvkFV15xhpry2R 19:48:35 INFO - Expected file size: 16086058 19:48:35 INFO - Obtained file size: 16086058 19:48:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 19:48:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 19:48:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 19:48:41 INFO - Http code: 200 19:48:41 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 19:48:41 INFO - x-amz-cf-id: mx8WSF7fzNHHvvcv5rmFz0n9oebeu4sR4Xtv-OAK0yv479AUrojv3w== 19:48:41 INFO - x-amz-version-id: Hb..EPRVCIj.4Ts5NB1JL49S1SV8ULc9 19:48:42 INFO - Expected file size: 14402992 19:48:42 INFO - Obtained file size: 14402992 19:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:42 INFO - trying https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:48:42 INFO - Downloading https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 19:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 19:48:44 INFO - Downloaded 82123879 bytes. 19:48:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:48:44 INFO - mkdir: /builds/slave/test/properties 19:48:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:48:44 INFO - Writing to file /builds/slave/test/properties/build_url 19:48:44 INFO - Contents: 19:48:44 INFO - build_url:https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 19:48:45 INFO - Running post-action listener: _resource_record_post_action 19:48:45 INFO - Running post-action listener: set_extra_try_arguments 19:48:45 INFO - [mozharness: 2017-01-25 03:48:45.514798Z] Finished download-and-extract step (success) 19:48:45 INFO - [mozharness: 2017-01-25 03:48:45.514900Z] Running populate-webroot step. 19:48:45 INFO - Running pre-action listener: _resource_record_pre_action 19:48:45 INFO - Running main action method: populate_webroot 19:48:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:48:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:48:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:48:45 INFO - '--tppagecycles', 19:48:45 INFO - '7'], 19:48:45 INFO - 'tests': ['tsvgm']}}, 19:48:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:48:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:48:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:48:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:48:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['damp', 'tps']}, 19:48:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'tests': ['damp', 'tps']}, 19:48:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['dromaeo_dom']}, 19:48:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:48:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['basic_compositor_video']}, 19:48:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:48:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'other-e10s': {'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['a11yr', 19:48:45 INFO - 'ts_paint', 19:48:45 INFO - 'tpaint', 19:48:45 INFO - 'sessionrestore', 19:48:45 INFO - 'sessionrestore_no_auto_restore', 19:48:45 INFO - 'tabpaint']}, 19:48:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['tsvgx', 19:48:45 INFO - 'tsvgr_opacity', 19:48:45 INFO - 'tart', 19:48:45 INFO - 'tscrollx', 19:48:45 INFO - 'cart']}, 19:48:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:48:45 INFO - 'tsvgr_opacity', 19:48:45 INFO - 'tart', 19:48:45 INFO - 'tscrollx', 19:48:45 INFO - 'cart']}, 19:48:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'talos_options': ['--disable-e10s'], 19:48:45 INFO - 'tests': ['tp5o']}, 19:48:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'tests': ['tp5o']}, 19:48:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'talos_options': ['--disable-e10s', 19:48:45 INFO - '--xperf_path', 19:48:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:45 INFO - 'tests': ['tp5n']}, 19:48:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:45 INFO - 'talos_options': ['--xperf_path', 19:48:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:45 INFO - 'tests': ['tp5n']}}, 19:48:45 INFO - 'talos.zip': {'path': '', 19:48:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:48:45 INFO - Running post-action listener: _resource_record_post_action 19:48:45 INFO - [mozharness: 2017-01-25 03:48:45.534030Z] Finished populate-webroot step (success) 19:48:45 INFO - [mozharness: 2017-01-25 03:48:45.534148Z] Running create-virtualenv step. 19:48:45 INFO - Running pre-action listener: _resource_record_pre_action 19:48:45 INFO - Running main action method: create_virtualenv 19:48:45 INFO - Creating virtualenv /builds/slave/test/build/venv 19:48:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:48:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:48:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:48:45 INFO - Using real prefix '/tools/python27' 19:48:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:48:46 INFO - Installing distribute.............................................................................................................................................................................................done. 19:48:49 INFO - Installing pip.................done. 19:48:49 INFO - Return code: 0 19:48:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:48:49 INFO - Reading from file tmpfile_stdout 19:48:49 INFO - Output received: 19:48:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:48:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:49 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:48:49 INFO - 'HOME': '/Users/cltbld', 19:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:48:49 INFO - 'LOGNAME': 'cltbld', 19:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:49 INFO - 'MOZ_NO_REMOTE': '1', 19:48:49 INFO - 'NO_EM_RESTART': '1', 19:48:49 INFO - 'PAGER': '/bin/cat', 19:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:49 INFO - 'PWD': '/builds/slave/test', 19:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:48:49 INFO - 'SHELL': '/bin/bash', 19:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:48:49 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:48:49 INFO - 'USER': 'cltbld', 19:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:49 INFO - 'XPC_FLAGS': '0x0', 19:48:49 INFO - 'XPC_SERVICE_NAME': '0', 19:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:48:49 INFO - Cleaning up... 19:48:49 INFO - Return code: 0 19:48:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:49 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:48:49 INFO - 'HOME': '/Users/cltbld', 19:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:48:49 INFO - 'LOGNAME': 'cltbld', 19:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:49 INFO - 'MOZ_NO_REMOTE': '1', 19:48:49 INFO - 'NO_EM_RESTART': '1', 19:48:49 INFO - 'PAGER': '/bin/cat', 19:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:49 INFO - 'PWD': '/builds/slave/test', 19:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:48:49 INFO - 'SHELL': '/bin/bash', 19:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:48:49 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:48:49 INFO - 'USER': 'cltbld', 19:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:49 INFO - 'XPC_FLAGS': '0x0', 19:48:49 INFO - 'XPC_SERVICE_NAME': '0', 19:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:50 INFO - Downloading/unpacking psutil>=3.1.1 19:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:48:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:53 INFO - Installing collected packages: psutil 19:48:53 INFO - Running setup.py install for psutil 19:48:53 INFO - building 'psutil._psutil_osx' extension 19:48:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:48:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:48:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:48:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:48:54 INFO - building 'psutil._psutil_posix' extension 19:48:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:48:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:48:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:48:54 INFO - ^ 19:48:54 INFO - 1 warning generated. 19:48:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:54 INFO - Successfully installed psutil 19:48:54 INFO - Cleaning up... 19:48:54 INFO - Return code: 0 19:48:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:48:54 INFO - 'HOME': '/Users/cltbld', 19:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:48:54 INFO - 'LOGNAME': 'cltbld', 19:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:54 INFO - 'MOZ_NO_REMOTE': '1', 19:48:54 INFO - 'NO_EM_RESTART': '1', 19:48:54 INFO - 'PAGER': '/bin/cat', 19:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:54 INFO - 'PWD': '/builds/slave/test', 19:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:48:54 INFO - 'SHELL': '/bin/bash', 19:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:48:54 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:48:54 INFO - 'USER': 'cltbld', 19:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:54 INFO - 'XPC_FLAGS': '0x0', 19:48:54 INFO - 'XPC_SERVICE_NAME': '0', 19:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:48:57 INFO - Installing collected packages: mozsystemmonitor 19:48:57 INFO - Running setup.py install for mozsystemmonitor 19:48:58 INFO - Successfully installed mozsystemmonitor 19:48:58 INFO - Cleaning up... 19:48:58 INFO - Return code: 0 19:48:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:58 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:48:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:48:58 INFO - 'HOME': '/Users/cltbld', 19:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:48:58 INFO - 'LOGNAME': 'cltbld', 19:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:58 INFO - 'MOZ_NO_REMOTE': '1', 19:48:58 INFO - 'NO_EM_RESTART': '1', 19:48:58 INFO - 'PAGER': '/bin/cat', 19:48:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:58 INFO - 'PWD': '/builds/slave/test', 19:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:48:58 INFO - 'SHELL': '/bin/bash', 19:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:48:58 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:48:58 INFO - 'USER': 'cltbld', 19:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:58 INFO - 'XPC_FLAGS': '0x0', 19:48:58 INFO - 'XPC_SERVICE_NAME': '0', 19:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:58 INFO - Downloading/unpacking jsonschema==2.5.1 19:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:49:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:49:01 INFO - warning: no files found matching '*.txt' 19:49:01 INFO - no previously-included directories found matching 'build' 19:49:01 INFO - no previously-included directories found matching 'dist' 19:49:01 INFO - no previously-included directories found matching '.git*' 19:49:01 INFO - Installing collected packages: jsonschema, functools32 19:49:01 INFO - Running setup.py install for functools32 19:49:01 INFO - warning: no files found matching '*.txt' 19:49:01 INFO - no previously-included directories found matching 'build' 19:49:01 INFO - no previously-included directories found matching 'dist' 19:49:01 INFO - no previously-included directories found matching '.git*' 19:49:01 INFO - Successfully installed jsonschema functools32 19:49:01 INFO - Cleaning up... 19:49:01 INFO - Return code: 0 19:49:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:01 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:49:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:01 INFO - 'HOME': '/Users/cltbld', 19:49:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:01 INFO - 'LOGNAME': 'cltbld', 19:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:01 INFO - 'MOZ_NO_REMOTE': '1', 19:49:01 INFO - 'NO_EM_RESTART': '1', 19:49:01 INFO - 'PAGER': '/bin/cat', 19:49:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:01 INFO - 'PWD': '/builds/slave/test', 19:49:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:01 INFO - 'SHELL': '/bin/bash', 19:49:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:01 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:01 INFO - 'USER': 'cltbld', 19:49:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:01 INFO - 'XPC_FLAGS': '0x0', 19:49:01 INFO - 'XPC_SERVICE_NAME': '0', 19:49:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:49:02 INFO - Cleaning up... 19:49:02 INFO - Return code: 0 19:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:02 INFO - 'HOME': '/Users/cltbld', 19:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:02 INFO - 'LOGNAME': 'cltbld', 19:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:02 INFO - 'MOZ_NO_REMOTE': '1', 19:49:02 INFO - 'NO_EM_RESTART': '1', 19:49:02 INFO - 'PAGER': '/bin/cat', 19:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:02 INFO - 'PWD': '/builds/slave/test', 19:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:02 INFO - 'SHELL': '/bin/bash', 19:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:02 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:02 INFO - 'USER': 'cltbld', 19:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:02 INFO - 'XPC_FLAGS': '0x0', 19:49:02 INFO - 'XPC_SERVICE_NAME': '0', 19:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - Downloading blobuploader-1.2.4.tar.gz 19:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:49:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:49:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:06 INFO - Downloading docopt-0.6.1.tar.gz 19:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:49:06 INFO - Installing collected packages: blobuploader, requests, docopt 19:49:06 INFO - Running setup.py install for blobuploader 19:49:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:49:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:49:06 INFO - Running setup.py install for requests 19:49:07 INFO - Running setup.py install for docopt 19:49:07 INFO - Successfully installed blobuploader requests docopt 19:49:07 INFO - Cleaning up... 19:49:07 INFO - Return code: 0 19:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:07 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:07 INFO - 'HOME': '/Users/cltbld', 19:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:07 INFO - 'LOGNAME': 'cltbld', 19:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:07 INFO - 'MOZ_NO_REMOTE': '1', 19:49:07 INFO - 'NO_EM_RESTART': '1', 19:49:07 INFO - 'PAGER': '/bin/cat', 19:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:07 INFO - 'PWD': '/builds/slave/test', 19:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:07 INFO - 'SHELL': '/bin/bash', 19:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:07 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:07 INFO - 'USER': 'cltbld', 19:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:07 INFO - 'XPC_FLAGS': '0x0', 19:49:07 INFO - 'XPC_SERVICE_NAME': '0', 19:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:49:07 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ZD7s3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:49:07 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ZjHALy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:49:07 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Lc1gkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:49:07 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-2ZgI0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-jdh0DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-vPinsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qAQ3Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-GOhg9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-K396Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-uqR509-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-APEc2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-O4jKUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-vChQfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:49:08 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-EazbZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:09 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Ae9FJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:49:09 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-j00rxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:49:09 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-OGeHK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:49:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:49:09 INFO - Running setup.py install for manifestparser 19:49:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:49:09 INFO - Running setup.py install for mozcrash 19:49:09 INFO - Running setup.py install for mozdebug 19:49:09 INFO - Running setup.py install for mozdevice 19:49:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:49:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:49:09 INFO - Running setup.py install for mozfile 19:49:10 INFO - Running setup.py install for mozhttpd 19:49:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Running setup.py install for mozinfo 19:49:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Running setup.py install for mozInstall 19:49:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Running setup.py install for mozleak 19:49:10 INFO - Running setup.py install for mozlog 19:49:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:49:10 INFO - Running setup.py install for moznetwork 19:49:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Running setup.py install for mozprocess 19:49:11 INFO - Running setup.py install for mozprofile 19:49:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Running setup.py install for mozrunner 19:49:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Running setup.py install for mozscreenshot 19:49:11 INFO - Running setup.py install for moztest 19:49:11 INFO - Running setup.py install for mozversion 19:49:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:49:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:49:11 INFO - Cleaning up... 19:49:11 INFO - Return code: 0 19:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:12 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:12 INFO - 'HOME': '/Users/cltbld', 19:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:12 INFO - 'LOGNAME': 'cltbld', 19:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:12 INFO - 'MOZ_NO_REMOTE': '1', 19:49:12 INFO - 'NO_EM_RESTART': '1', 19:49:12 INFO - 'PAGER': '/bin/cat', 19:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:12 INFO - 'PWD': '/builds/slave/test', 19:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:12 INFO - 'SHELL': '/bin/bash', 19:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:12 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:12 INFO - 'USER': 'cltbld', 19:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:12 INFO - 'XPC_FLAGS': '0x0', 19:49:12 INFO - 'XPC_SERVICE_NAME': '0', 19:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:49:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Th_hIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-DV9QXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-8iGb8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-gqetyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-S0qh62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Ln8nRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-B0IVoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Fves6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-jIa7Hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:49:13 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Zr23Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-__c8aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-tW8LBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-030qhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iGxgj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-IQt4vM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ImBETt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:49:14 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-sd0Toc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:49:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:17 INFO - Downloading blessings-1.6.tar.gz 19:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:49:17 INFO - Installing collected packages: blessings 19:49:17 INFO - Running setup.py install for blessings 19:49:17 INFO - Successfully installed blessings 19:49:17 INFO - Cleaning up... 19:49:18 INFO - Return code: 0 19:49:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:49:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:49:18 INFO - Reading from file tmpfile_stdout 19:49:18 INFO - Current package versions: 19:49:18 INFO - blessings == 1.6 19:49:18 INFO - blobuploader == 1.2.4 19:49:18 INFO - docopt == 0.6.1 19:49:18 INFO - functools32 == 3.2.3-2 19:49:18 INFO - jsonschema == 2.5.1 19:49:18 INFO - manifestparser == 1.1 19:49:18 INFO - mozInstall == 1.12 19:49:18 INFO - mozcrash == 1.0 19:49:18 INFO - mozdebug == 0.1 19:49:18 INFO - mozdevice == 0.48 19:49:18 INFO - mozfile == 1.2 19:49:18 INFO - mozhttpd == 0.7 19:49:18 INFO - mozinfo == 0.9 19:49:18 INFO - mozleak == 0.1 19:49:18 INFO - mozlog == 3.4 19:49:18 INFO - moznetwork == 0.27 19:49:18 INFO - mozprocess == 0.23 19:49:18 INFO - mozprofile == 0.28 19:49:18 INFO - mozrunner == 6.13 19:49:18 INFO - mozscreenshot == 0.1 19:49:18 INFO - mozsystemmonitor == 0.3 19:49:18 INFO - moztest == 0.8 19:49:18 INFO - mozversion == 1.4 19:49:18 INFO - psutil == 3.1.1 19:49:18 INFO - requests == 1.2.3 19:49:18 INFO - wsgiref == 0.1.2 19:49:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:18 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:18 INFO - 'HOME': '/Users/cltbld', 19:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:18 INFO - 'LOGNAME': 'cltbld', 19:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:18 INFO - 'MOZ_NO_REMOTE': '1', 19:49:18 INFO - 'NO_EM_RESTART': '1', 19:49:18 INFO - 'PAGER': '/bin/cat', 19:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:18 INFO - 'PWD': '/builds/slave/test', 19:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:18 INFO - 'SHELL': '/bin/bash', 19:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:18 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:18 INFO - 'USER': 'cltbld', 19:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:18 INFO - 'XPC_FLAGS': '0x0', 19:49:18 INFO - 'XPC_SERVICE_NAME': '0', 19:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:49:18 INFO - Cleaning up... 19:49:18 INFO - Return code: 0 19:49:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:18 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 0x10dbfd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db73570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82cb4ae1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, '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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:18 INFO - 'HOME': '/Users/cltbld', 19:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:18 INFO - 'LOGNAME': 'cltbld', 19:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:18 INFO - 'MOZ_NO_REMOTE': '1', 19:49:18 INFO - 'NO_EM_RESTART': '1', 19:49:18 INFO - 'PAGER': '/bin/cat', 19:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:18 INFO - 'PWD': '/builds/slave/test', 19:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:18 INFO - 'SHELL': '/bin/bash', 19:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:18 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:18 INFO - 'USER': 'cltbld', 19:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:18 INFO - 'XPC_FLAGS': '0x0', 19:49:18 INFO - 'XPC_SERVICE_NAME': '0', 19:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:49:18 INFO - Cleaning up... 19:49:18 INFO - Return code: 0 19:49:18 INFO - Running post-action listener: _resource_record_post_action 19:49:18 INFO - Running post-action listener: _start_resource_monitoring 19:49:18 INFO - Starting resource monitoring. 19:49:18 INFO - [mozharness: 2017-01-25 03:49:18.906380Z] Finished create-virtualenv step (success) 19:49:18 INFO - [mozharness: 2017-01-25 03:49:18.907408Z] Running install step. 19:49:18 INFO - Running pre-action listener: _resource_record_pre_action 19:49:18 INFO - Running main action method: install 19:49:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:49:19 INFO - Reading from file tmpfile_stdout 19:49:19 INFO - Detecting whether we're running mozinstall >=1.0... 19:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:49:19 INFO - Reading from file tmpfile_stdout 19:49:19 INFO - Output received: 19:49:19 INFO - Usage: mozinstall [options] installer 19:49:19 INFO - Options: 19:49:19 INFO - -h, --help show this help message and exit 19:49:19 INFO - -d DEST, --destination=DEST 19:49:19 INFO - Directory to install application into. [default: 19:49:19 INFO - "/builds/slave/test"] 19:49:19 INFO - --app=APP Application being installed. [default: firefox] 19:49:19 INFO - mkdir: /builds/slave/test/build/application 19:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:49:41 INFO - Reading from file tmpfile_stdout 19:49:41 INFO - Output received: 19:49:41 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:49:41 INFO - Running post-action listener: _resource_record_post_action 19:49:41 INFO - [mozharness: 2017-01-25 03:49:41.031131Z] Finished install step (success) 19:49:41 INFO - [mozharness: 2017-01-25 03:49:41.031274Z] Running run-tests step. 19:49:41 INFO - Running pre-action listener: _resource_record_pre_action 19:49:41 INFO - Running main action method: run_tests 19:49:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:49:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:49:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:49:41 INFO - 2017-01-24 19:49:41.045 screenresolution[1624:7322] starting screenresolution argv=screenresolution get 19:49:41 INFO - 2017-01-24 19:49:41.048 screenresolution[1624:7322] Display 0: 1600x1200x32@60 19:49:41 INFO - 2017-01-24 19:49:41.053 screenresolution[1625:7325] starting screenresolution argv=screenresolution list 19:49:41 INFO - Available Modes on Display 0 19:49:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:49:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:49:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:49:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:49:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:49:41 INFO - Intel Iris: 19:49:41 INFO - Chipset Model: Intel Iris 19:49:41 INFO - Type: GPU 19:49:41 INFO - Bus: Built-In 19:49:41 INFO - VRAM (Dynamic, Max): 1536 MB 19:49:41 INFO - Vendor: Intel (0x8086) 19:49:41 INFO - Device ID: 0x0a2e 19:49:41 INFO - Revision ID: 0x0009 19:49:41 INFO - Displays: 19:49:41 INFO - Display: 19:49:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:49:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:49:41 INFO - Display Serial Number: 1600x1200 60 19:49:41 INFO - Main Display: Yes 19:49:41 INFO - Mirror: Off 19:49:41 INFO - Online: Yes 19:49:41 INFO - Rotation: Supported 19:49:41 INFO - Return code: 0 19:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:49:41 INFO - Python 2.7.3 19:49:41 INFO - Return code: 0 19:49:41 INFO - grabbing minidump binary from tooltool 19:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:41 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 0x10da11d30>, '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 0x10d877db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da09b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:49:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:49:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:49:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:49:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:49:41 INFO - Return code: 0 19:49:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:49:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:49:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:49:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:49:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XxcHnfZt6K/Render', 19:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:41 INFO - 'HOME': '/Users/cltbld', 19:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:41 INFO - 'LOGNAME': 'cltbld', 19:49:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:49:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:41 INFO - 'MOZ_NO_REMOTE': '1', 19:49:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:49:41 INFO - 'NO_EM_RESTART': '1', 19:49:41 INFO - 'PAGER': '/bin/cat', 19:49:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:41 INFO - 'PWD': '/builds/slave/test', 19:49:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:41 INFO - 'SHELL': '/bin/bash', 19:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xN3LOS9Cex/Listeners', 19:49:41 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 19:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:41 INFO - 'USER': 'cltbld', 19:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:41 INFO - 'XPC_FLAGS': '0x0', 19:49:41 INFO - 'XPC_SERVICE_NAME': '0', 19:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:49:41 INFO - mozversion application_buildid: 20170124172347 19:49:41 INFO - mozversion application_changeset: 4663bc61d861d9a967db3fb7d59f08c13c740ca5 19:49:41 INFO - mozversion application_display_name: Firefox 19:49:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:49:41 INFO - mozversion application_name: Firefox 19:49:41 INFO - mozversion application_remotingname: firefox 19:49:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:49:41 INFO - mozversion application_vendor: Mozilla 19:49:41 INFO - mozversion application_version: 52.0 19:49:41 INFO - mozversion platform_buildid: 20170124172347 19:49:41 INFO - mozversion platform_changeset: 4663bc61d861d9a967db3fb7d59f08c13c740ca5 19:49:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:49:41 INFO - mozversion platform_version: 52.0 19:49:41 INFO - using testdate: 1485316181 19:49:41 INFO - actual date: 1485316181 19:49:41 INFO - starting webserver on 'localhost:49239' 19:49:41 INFO - SUITE-START | Running 2 tests 19:49:41 INFO - TEST-START | dromaeo_css 19:49:41 INFO - operating with platform_type : mac_ 19:49:41 INFO - Initialising browser for dromaeo_css test... 19:49:41 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmplpai1d/profile http://localhost:49239/getInfo.html) 19:49:44 INFO - PROCESS | 1635 | 2017-01-24 19:49:44.463 plugin-container[1638:7503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c37, name = 'com.apple.tsm.portname' 19:49:44 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:44 INFO - PROCESS | 1635 | 2017-01-24 19:49:44.464 plugin-container[1638:7503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 19:49:44 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:44 INFO - PROCESS | 1635 | 2017-01-24 19:49:44.464 plugin-container[1638:7503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:49:44 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 19:49:44 INFO - PROCESS | 1635 | colorDepth:24 19:49:44 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 19:49:44 INFO - PROCESS | 1635 | __metrics 19:49:44 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 19:49:45 INFO - TEST-INFO | 1635: exit 0 19:49:45 INFO - Browser initialized. 19:49:45 INFO - Running cycle 1/1 for dromaeo_css test... 19:49:45 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmplpai1d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:47 INFO - PROCESS | 1639 | 2017-01-24 19:49:47.859 plugin-container[1640:7636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 19:49:47 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:47 INFO - PROCESS | 1639 | 2017-01-24 19:49:47.860 plugin-container[1640:7636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 19:49:47 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:47 INFO - PROCESS | 1639 | 2017-01-24 19:49:47.860 plugin-container[1640:7636] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:49:57 INFO - PROCESS | 1639 | RSS: Main: 176984064 19:49:57 INFO - PROCESS | 1639 | 19:53:00 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 19:53:02 INFO - PROCESS | 1639 | RSS: Main: 182337536 19:53:02 INFO - PROCESS | 1639 | 19:56:04 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 19:56:04 INFO - PROCESS | 1639 | RSS: Main: 191746048 19:56:04 INFO - PROCESS | 1639 | 19:59:06 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 19:59:07 INFO - PROCESS | 1639 | RSS: Main: 198979584 19:59:07 INFO - PROCESS | 1639 | 20:02:08 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 20:02:09 INFO - PROCESS | 1639 | RSS: Main: 202031104 20:02:09 INFO - PROCESS | 1639 | 20:05:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 20:05:13 INFO - PROCESS | 1639 | RSS: Main: 204410880 20:05:13 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 20:08:15 INFO - PROCESS | 1639 | RSS: Main: 204861440 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | __start_tp_report 20:08:15 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 20:08:15 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 20:08:15 INFO - PROCESS | 1639 | |i|pagename|runs| 20:08:15 INFO - PROCESS | 1639 | |0;dojo.html;3226.032190342897;3598.4242521196597;3606.0984753811554;3623.18478342373;3627;5891.528677705784;5964.79123230687;5986.341502434734;5986.9101963470475;6008.549358798085;4522.525134860842;5853.76584936602;5953.255843019622;6015.548833837458;6041.728122234499;5405.756740946657;5468.179773034057;5496.31296087989;5512.255845460852;5523.171524271372;5433.891322173554;5481.972590137064;5528.5024347808685;5529.419410961851;5553.583481238903;5389.137737961927;5414.1608050752;5517.144842549411;5536.086545719952;5574.637648552831;5463.07127788273;5488.067028605146;5504.559635229192;5507.311586051744;5518.310211223597;4580.702254353457;5340.54605358545;5481.808136715196;5485.533729632986;5540.667559946416;5933.376995415462;5975.25309336333;6076.754265375587;6078.483328917047;6098.664573448458;5540.9168862467095;6222.906656400158;6289.182406287199;6312.116303717483;6342.587731797465;6266.4360207581085;6301.054841773725;6306.463950564207;6313.936860631381;6347.936520634781;6097.115918191837;6098.817035488943;6102.237220347457;6109.022556390956;6119.112728654321;5540.252065971123;6321.051842223611;6453.612783233021;6537.934620653733;6550.443212326911;6334.778282760081;6338.936610633927;6351.61890286575;6354.4281014708895;6367.7452901884335;6342.175517182736;6346.365363463709;6365.809025729143;6366.395192456709;6373;6234.220722409699;6280.309165991737;6300.180976473029;6300.9684951574945;6313.05304204373;6303.3066362700065;6318.873622527615;6319.55763096588;6322.620642761357;6329.113924050636;5115.411727651293;5117.7717347837;5122.692638441706;5124.948750512522;5134.897302053938;4510.72935623857;4520.434945631796;4563.06457176278;4595.333676616872;4602;5313.229581710631;5541.889162216733;6229.719662615194;6243.344448832898;6269.404406581368;6361.4592759615025;6379.425851673372;6383.202099737533;6387.904181437282;6397.040443933354;6102.115193296947;6141.516692814271;6161.291451483047;6194.628322300677;6206.317305096436;5626.943730562723;5632.155176723442;5633.521150702154;5679.977604031314;5692.345380281238;6505.024246363083;6578.8355291118105;6583.670816459158;6609.504287178481;6615.768448104293;6193.535484838565;6210.5031597473;6266.624002559861;6373.553851230555;6376.872462550816;6457.7094030767785;6571.112899758471;6572.079908812866;6580.736770529125;6583.473322134314;6340.619562826245;6347.5556711029785;6359.395857393541;6373.936260637334;6388.0737293091315;6142.57002009855;6282.780102696566;6330.556861019684;6332.651704156316;6358.809235722936;6243.937560624336;6251.937480625317;6264.812055638338;6345.143405640364;6347.96826015867;5372.262193425296;5718.456746609066;6250.406211409909;6257.749690012349;6378.138951241709;6063.9696801517475;6174.197354343906;6210.099535567408;6234.719437625227;6244.843878903063;6209.59637623553;6238.970569856105;6246.156768836331;6292;6320.905186422116;5056.949430505648;5178.948210517847;6200.7519699213335;6275.309715931153;6279.309275979547 20:08:15 INFO - PROCESS | 1639 | |1;ext.html;450.4167103600837;523.7800123947942;539.7085573790154;543.8178210299546;543.8232574413316;40504.797476012616;42558.787206063964;42641.57358426419;44065.339019914725;44290.89272768182;42270.788646056804;42906.57093429065;42947.78526107373;42960;43176;9726.902730972688;9854.605815767396;9855.605775768996;9860.21118310537;9874.210063194927;9748;9765.169960553361;9862;9867.654632087879;9878.456684882329;9768.755781105494;9842.557084931159;9884.505774711271;9902.356346837469;9919.801603967917;9810.018998100204;9844.114029737359;9868.851967220497;9872.851907221397;9878.259130565164;9696;9746.610135594568;9753.21974242059;9755.853662195073;9788.119069283728;40454.79772601148;40734.59265407338;40786.79606601978;41220.76337709874;41234;3325.5510506081855;3485.7385696072897;3654.451832225161;3900.570937196906;4128.236276288896;30388;30700.2324941876;30886.845565772026;31674;31900.521492177635;3204.7436205103536;3294.884679036222;3300.1089705779304;3357;3366.4950257461333;375227.12386437895;377900.1104994485;379840;379950;380248;62293.37706622876;62340;62740.05889911655;62830;63158.0526292106;64692.029619555746;65133.02300465497;65256;65256.67371663206;65352.34647653463;16730.6653866922;16859.662806743796;17029.659406811796;17034.06312652791;17076.80462367647;12891.871081289255;13073.346332683328;13179.341032948314;13183.011274154476;13184.472621095241;16200.108994005208;17287.913560432116;17715.645687086188;17787.46637600874;17917.477014453656;2798.496270671258;6660.8667826643195;6874.621895795679;6903.689333979983;6912.585244885323;845.9407841451163;926.8424367857481;958.1807554540907;1028.9125424338865;1075.4891426572472;30430;30535.847320763252;30673.62664678101;30909.227269318;31028.53457198144;895.9114675669093;959.3140904253493;977.193815102529;1070.117153348475;1112.9220954533105;14669.853301466848;16043.278052487665;16116.75824862628;16149.919250403907;16421.42525011619;696046;701460;702168;702423;703360;43533;43820.78089609532;43850.68447946503;43875.78062109669;43974.340384894254;1387.486629946926;1396.3576754692751;1412.639776856895;1417.9716405672036;1424.921629310398;29921.850390747906;30185.32231372125;30582.235444114074;30921;31264.687353126177;51851.703707407614;52426.213606795165;52846.471535285695;53032;53146.937061257784;619599;620337;625711;626600;627711.8614406899;27340.1797946054;27680;27925.16224513268;27998.580021300106;28069.298267543476;36973.81513092525;47739;48097.03805923932;48479;48575.78560536015;39503;40314.798426007685;40625;40901.97745056397;41442.1711565773;33582.66417335795;39459.40810887782;39687.40468893027;39888.40167397433;40709;38471.615283846804;38641.806790965864;38974;39039;39373.21253574855;33354.81196209787;35057.298854023284;35372.46941295934;35423.64576354204;35503;23297.534049319256;26484;26674.24571060979;26749;26762.063327783435 20:08:15 INFO - PROCESS | 1639 | |2;jquery.html;379.356989902116;383.1876421985391;389.77977442744844;397.4455634390028;411.9670426365891;19322.90338548307;20897.26859559916;20926.162953481842;21568.784312156873;21783.801890895993;20266.392008239738;20337.694934575993;20488.18047278107;20542.486437839063;20613.793862061375;4980.676256043345;5805.303363596364;5984.8503787405225;6034.668093254869;6049.395060493959;5147.7426128693605;5875.265591801025;5975.790847320344;5987.730552125143;6089.056196289582;5739.885202295973;5872.442117998783;5926.436988486087;5934.5845790794665;5954.4938680212235;4952.925706114411;5215.556677682363;5880.7059647017695;5947.494462970652;5989.490893274077;4505.5269196734525;5146.562542183919;5156.226566015091;5271.736413179364;5279.70961597112;20086.296979605642;24430;25849.966001359924;26046.86976565124;26269.949202031898;2032.9085191166432;2272.522770218256;2296.7588403217587;2376.3821406434276;2563.7564431378987;10130.797384052277;10172.982701729768;10193.439360835151;10471.528781204865;10472.26694131411;1876.8967706776123;1979.2478858033999;2012.3359291433937;2019.3840878532042;2026.290798220611;111195;126680.0997985031;131845;135549;137061.94407084002;9485.66800161991;11467.942660286812;11738.76522469563;11882.643520694393;11900.404979750978;14854.777178342334;14998.325075371635;15024.924875375553;15042.548723538312;15227.314770835339;8488.74533763988;8704.216620504185;8810.559472026373;8860.424072435271;8900.643974241088;8567.271781898484;8629.352798540134;8685.348598855111;8790.560471976376;8795.560221988875;3757.323681737259;4638.884027899262;4645.883852903637;4669.112868554964;4731.313959475926;1953.4335042837702;1974.1017836884182;2018;2020.3130935481713;2021.6158929803291;871.2724874729672;952.4713783849892;1005.958832608247;1021.7241344836852;1047.2878442659064;17196.914015429844;17277;21163.40222162236;21275.574488510145;22837.771622283566;957.1624828275259;967.908048735369;998.3710262534556;1014.9847752283722;1060.867391576053;10843.728906777236;11104.333739975627;11106.500207490682;11177.32936023825;11181.273217240854;611570;620730;629682;630459;633428.3314333687;84688.15311846926;84793;137728;137831;138513.30743346422;994.8213412626325;1026.3585259212844;1035.0839507036328;1056.6829951014818;1058.9841152382721;19354.806451935863;19663;19893.502662433555;20010.89994550018;20083.497912552302;50200.748996254784;55436.72281638566;57947;58048.709756450946;60667.39332606617;85514.57242713746;88079.1192088071;88221;88427.55786221028;89277;7222.855542889218;8138.593070346341;8226.30076443485;8299;8372.28835548985;11015.614453494089;13290.401931912893;13309.600711978655;13326.733465330833;13341.733165336833;11141.331520108819;11424;11525.366104864313;11644.708882278012;11830.40847957616;11031.724206894893;11190.160737944523;11278.210525263154;11487.425628718363;11584.826227606423;11633;11688.298702077902;11869.643910682693;11946.402679865798;12142.575009874612;8239.475696995834;8393.664253429795;8514.361422893184;8527.445716028438;8590.226879580869;8464.57677116154;10832.458377080957;11548.884511154782;11597.246178998337;11836 20:08:15 INFO - PROCESS | 1639 | |3;mootools.html;1349.6423447786335;1386.2029333133448;1661.4434164554873;1815.7276408538726;1877.586930875207;7620.3141717245435;7839;8049.678012879485;8538.658453661854;8567.828643427136;8024.839503209932;8258.710945116922;8268.586570671458;8389;8399.832003359928;2331.8367714259925;2350.976490235101;2363.8463499872455;2366.124533922451;2376.3583832365252;2370.9762902371012;2397.6283676030243;2418.661387405765;2422.5518279118332;2426.4419183587784;2351.835371523994;2358.716953965518;2373.6558199061124;2374.1097088591778;2374.774396432345;2323.372689373868;2325.4070212095808;2330.2776139396674;2344.4138965258685;2346.554154710608;2358.3750306168877;2363.988180059089;2368.4789346343778;2375.0737212487143;2382.8808559571953;8176.550289734062;8337.95831020841;8397.160283971614;8406.159384061606;8413.83172336556;4345.435093437864;5231.659942103751;5252.448492908228;5252.8686782830355;5257.737113144366;3518.665726755954;3527.2416430467315;3572.8392222350058;3594.263176048904;3660.524131862841;1079.4440862955585;1094.8795632480421;1110.156281226274;1122.9887701122966;1131.389049913046;9195.264378849675;10702.839457408145;10731.248812583122;10777.976092271221;10802.891971080267;4273.166732487197;4429.579189976947;4517.164324599959;4521.299198624219;4550.772461376984;8519.48883067018;8660.350473714496;8663.74008779725;8681.175288347597;8690.739277821676;5142.331496905454;5153.2270159476375;5160.071187186268;5160.690358578498;5183.766730497136;4373.628241599499;4473.955260447419;4675.345451636774;4786.1624215762795;4806.086843499724;3141.701538353853;3183.4588120019653;3681.4661874028116;3789.9431508527396;3817.541894972621;1157.65270418874;1169.0939521870619;1169.2633640806237;1173.4543437301695;1174.7004513848915;793.6031984007996;826.9173082691683;904.841652710773;910.6812615584493;937.6155776131753;5516.365617953907;5556.91664625031;5680.318361796611;5689.6586204827845;5831.125331200269;864.9221570058725;1025.1183981775666;1038.4392428088747;1039.542601255445;1057.6509751781894;3296.9340613188083;3723.1436769543157;3829.827657755352;3855.190410013872;3865.864694735727;9742.269329800152;9995.000499950092;10080.848787268196;10110.292279540508;10113.69658910234;10012.849807252896;10089.646862359783;10101.949490252502;10119.949400252952;10149.137323327452;7527.698892044257;7747.109082455477;7944.046714394194;7955.880661790078;7960.203979602109;5664.660120392789;5684.744186511534;5840.620359676607;5870.647761134345;5904.1143828424365;9004.41471304363;9109.089091090838;9124.680636177967;9312.720618381189;9382.202512786489;7988.560629165451;9307.813843723223;9330.673426430043;9363.4381937084;9482.146606805421;6599.076129341992;6802.795916122524;7184.281571842774;7279.980802687734;7549.245075492627;7817.37461003107;7949.085855126735;8035;8043.1554686757045;8062.475939063942;7095.361417472453;7136.643167841691;7286.635668216462;7327.120745510573;7355.448341374311;6089.756409743561;7024.473164512784;7053.365197132282;7264.128304603481;7267.854642907007;6673.866522669617;6722.394984451423;6752.155980502437;6795.25252222265;6807.761728339485;4783.808647654055;4849.927251091307;4899.902001959869;4935.432425271139;4978.352814134139;4235.809388577583;4860.684055536521;4873.4882837301575;4981.37732783402;5012.122878496176 20:08:15 INFO - PROCESS | 1639 | |4;prototype.html;287.3835622589545;305.7401208972375;313.8716314848121;318.5079052863326;319.5765610565999;5433.9298296462475;5444.373897001846;5461.563074954004;5537.141743029832;5557.499825015742;4449.888752781182;4640;4961.578265847407;5380.663988327233;5407.215953686723;3687.944680829776;4160.625543701074;4173.415721798935;4182.644475219595;4215.641670458;3342.8328583570847;3664.9633503665023;3871.2257548490416;4018.819153138116;4187.937180942273;3559.3771090059267;3747.625237476258;4067.247559201557;4115.732477388975;4116.91766164678;3422.144463884029;4101.794910254475;4106.77412742299;4111.013356794363;4129.050318426749;3026.7124623160767;3580.5703315602295;3595.2989167112423;3622.7826330420257;3634.945475817865;4261.850835220784;4790.4251489821445;5103.56619687327;5172.301739265226;5178.430372659004;4089.9182016359505;4697.365855609483;4715.646326525524;4719.575238228542;4739.502352252998;4717.1980763269985;4798.616110711135;5431.456854314576;5654.434556544354;5709.800156994485;3023.9244018899485;3583.498310236569;3630.7640003399692;3910.7066969977363;3980.4825372701653;5264.31563896691;5337.759800808973;5451.427600101972;5779.450952159538;5987.700614969234;2970.5395663672166;3980.980095099506;3983.24318379507;3986;3998.8600398985895;3723.72072094594;3919.902427320355;4010.2981978153707;4032.052467670095;4042.555318914917;4251.427886128909;4279.7218180818145;4607.493175750665;4653.86038418848;4686.906261874743;5743.023685973311;5748.281464816898;5757.193992841006;5769.624974376652;5773.480386765127;1185.6206014075412;1231.150506150753;1238.7336722604502;1286.8354139503765;1297.8831905128584;816.8645582640135;836.0803116571723;847.2967437027253;875.2560323724783;900.0009988912302;5218.503602586276;5238.816641417532;5269.631125821155;5272.894542109137;5286.550643195372;4775.2598347256235;5116.155834287301;5306.681599103988;5644.186487917452;5689.6586204827845;5124.540220694013;5164.406093299319;5178.249153872667;5200.791968321225;5201.973990130025;2464.9770345306497;2642.947141057168;2667.906158475016;2926.956095658567;2929.868155932988;5046.949530504721;5534.4742249486235;5659.292588426447;5702.460335709336;5798.82603521895;4627.9768601156775;4904.779284932187;5276.947230527722;5668.659880407189;5719.142128680731;4982.950170498394;5092.3335198656905;5716.228309178207;5765.875654247381;5784.334801497882;4021.5777343378522;5300.840974770763;5533.3083364579425;5554.444555544414;5568;4512.653420221401;4642.953570464388;5474.616776825584;5477.7814984931165;5479.205515200353;4265.293882447046;5736.885262294814;5771.538276937918;5791.8416316735975;5806.44838740319;3490.842912068912;3933.233019561158;3977.6618987386396;3996.300647386754;3998.1004274039274;5893.852653683693;5930.822075337746;5989.940100598938;6016.187814645142;6086.1175129606845;4910.189818679878;5545.445455454584;5738.483536481736;5842.912356314744;5867.383924688017;4992.700437973734;5727.11229759388;5743.569232307677;5790.334111577054;5812.912806307824;5216.452272511332;5428.561431220651;5527.364353099284;5679.801206957736;5684.403137670651;5579.69311687861;5610.971945140248;5731.478149614157;5731.828045158652;5866.384029676822;4671.602913752369;5161.22581612746;5266.735303819394;5310.522053015247;5560.944390556043 20:08:15 INFO - PROCESS | 1639 | |5;yui.html;611.3061674998876;706.4030893894658;776.7553220735468;793.1037927142328;825.7440193540001;13132.146410483323;14269.857301426982;15201.467948621801;15214.467493637725;15660.451884184056;12234.93882530586;12499.187552809084;13605.523806666768;14184.432622695105;14204.857951420483;831.5301854452246;847.1697736218525;851.259404318244;867.5141920524527;871.6252011634995;685.835399504118;710.3997122431563;828.6146941672122;858.6050416808275;860.3461369359301;896.9461832290084;904.9909500904972;914.7804526913559;917.5779141594875;917.5962576466334;874.641397027219;908.500324821349;912.1608120529129;913.6893456224915;914.3371055984425;648.3581254558001;657.1982181738272;674.1741366825648;678.7454704485817;678.8710145072421;14943.925280373638;15257.38970441181;15346.386144554204;15504.30230639624;15572;14024.228667423287;14422.701956823936;14464.13215207091;14529.709405811824;14550.781738273934;11882.049436045096;11946.223495472766;11958.162928595002;12000.879991200063;12157.93921030398;3759.793211373373;3779.395296752534;3799.35410980134;3820.6752426043818;3863.2659794639208;94949.52525237399;99180.50409747977;99843.50078249635;102354.95290094231;102486;10483.790324193473;10529;10750.193735469715;10918.181136414642;10964.122870170368;17716.822831771777;21279.893600532207;21289.467763305733;21315.401344899194;21340.893295533424;7481.513701609487;7770.961145194238;7891.526508409514;7915.722949696848;8090.676372945016;3878.5927477614446;4398.714083584557;4434.224010798098;4511.390962220124;4544.341070544752;3276.19733165376;3353.111425472252;3380.4084285250474;3384.4415671414436;3387.288669379458;630.2877748144567;630.3255516597197;643.8615697625033;645.631989765829;650.3756393861851;536.9355677318747;585.7276366489602;586.9325027621878;596.4363676325917;602.8523011862122;7155.642217889084;8529.402941794015;8535.359848011423;8618.479144737175;8775.473471591726;593.6912805341199;614.2690198663576;628.4438272129198;641.4964253061418;645.9838504037343;4017.8392864285684;4198.748075115503;4210.45264115663;4322.438083049184;4331.417406009646;113688;113830.43084784689;117063;119636;120165;103962.48018759857;104726.95273047172;104915;106321.46839265754;106520;84775;86648.13351866527;88017.55991220131;88082.55958720166;90779.54610227038;8172.530082014571;8273.83452330962;8425.4944703318;8545.786355341166;8559.486430814171;56836.43163568311;57125;58100.41899581119;58362.708186458796;59282;65658;71946.28053719396;85161.57419212864;85175.574122129;89226.55386723025;10099.848502272618;10401.791964160522;10521.27739136926;10555;10603.257771956198;11684.298942063504;11717.941410292893;11895.821562676394;11961.162718609961;12276.631701048982;11060.225784195029;11367.20429569922;13133.027134888043;13360.599182024554;13606.979476539102;13124.868751312746;13540.390682419511;13703.520376786764;13719.588412347646;13740.381682824496;11706.765864682488;12679.683007924876;12796.424160912595;13086.411111500196;13138.146020508637;11110.277831940897;11315.547378104784;11486.081113511067;11663;11774.116941229271;9169.908300916906;10249.487525623837;11470.713232169262;11484.940624164185;11489.195756297313 20:08:15 INFO - PROCESS | 1639 | __end_tp_report 20:08:15 INFO - PROCESS | 1639 | __start_cc_report 20:08:15 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,366 20:08:15 INFO - PROCESS | 1639 | __end_cc_report 20:08:15 INFO - PROCESS | 1639 | __startTimestamp1485317295704__endTimestamp 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | ------- Summary: start ------- 20:08:15 INFO - PROCESS | 1639 | Number of tests: 6 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5909.44 Median:6206.32 stddev:648.49 (10.4%) stddev-sans-first:615.36 20:08:15 INFO - PROCESS | 1639 | Values: 3226.0 3598.4 3606.1 3623.2 3627.0 5891.5 5964.8 5986.3 5986.9 6008.5 4522.5 5853.8 5953.3 6015.5 6041.7 5405.8 5468.2 5496.3 5512.3 5523.2 5433.9 5482.0 5528.5 5529.4 5553.6 5389.1 5414.2 5517.1 5536.1 5574.6 5463.1 5488.1 5504.6 5507.3 5518.3 4580.7 5340.5 5481.8 5485.5 5540.7 5933.4 5975.3 6076.8 6078.5 6098.7 5540.9 6222.9 6289.2 6312.1 6342.6 6266.4 6301.1 6306.5 6313.9 6347.9 6097.1 6098.8 6102.2 6109.0 6119.1 5540.3 6321.1 6453.6 6537.9 6550.4 6334.8 6338.9 6351.6 6354.4 6367.7 6342.2 6346.4 6365.8 6366.4 6373.0 6234.2 6280.3 6300.2 6301.0 6313.1 6303.3 6318.9 6319.6 6322.6 6329.1 5115.4 5117.8 5122.7 5124.9 5134.9 4510.7 4520.4 4563.1 4595.3 4602.0 5313.2 5541.9 6229.7 6243.3 6269.4 6361.5 6379.4 6383.2 6387.9 6397.0 6102.1 6141.5 6161.3 6194.6 6206.3 5626.9 5632.2 5633.5 5680.0 5692.3 6505.0 6578.8 6583.7 6609.5 6615.8 6193.5 6210.5 6266.6 6373.6 6376.9 6457.7 6571.1 6572.1 6580.7 6583.5 6340.6 6347.6 6359.4 6373.9 6388.1 6142.6 6282.8 6330.6 6332.7 6358.8 6243.9 6251.9 6264.8 6345.1 6348.0 5372.3 5718.5 6250.4 6257.7 6378.1 6064.0 6174.2 6210.1 6234.7 6244.8 6209.6 6239.0 6246.2 6292.0 6320.9 5056.9 5178.9 6200.8 6275.3 6279.3 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:70254.18 Median:40760.69 stddev:156665.69 (384.4%) stddev-sans-first:157017.52 20:08:15 INFO - PROCESS | 1639 | Values: 450.4 523.8 539.7 543.8 543.8 40504.8 42558.8 42641.6 44065.3 44290.9 42270.8 42906.6 42947.8 42960.0 43176.0 9726.9 9854.6 9855.6 9860.2 9874.2 9748.0 9765.2 9862.0 9867.7 9878.5 9768.8 9842.6 9884.5 9902.4 9919.8 9810.0 9844.1 9868.9 9872.9 9878.3 9696.0 9746.6 9753.2 9755.9 9788.1 40454.8 40734.6 40786.8 41220.8 41234.0 3325.6 3485.7 3654.5 3900.6 4128.2 30388.0 30700.2 30886.8 31674.0 31900.5 3204.7 3294.9 3300.1 3357.0 3366.5 375227.1 377900.1 379840.0 379950.0 380248.0 62293.4 62340.0 62740.1 62830.0 63158.1 64692.0 65133.0 65256.0 65256.7 65352.3 16730.7 16859.7 17029.7 17034.1 17076.8 12891.9 13073.3 13179.3 13183.0 13184.5 16200.1 17287.9 17715.6 17787.5 17917.5 2798.5 6660.9 6874.6 6903.7 6912.6 845.9 926.8 958.2 1028.9 1075.5 30430.0 30535.8 30673.6 30909.2 31028.5 895.9 959.3 977.2 1070.1 1112.9 14669.9 16043.3 16116.8 16149.9 16421.4 696046.0 701460.0 702168.0 702423.0 703360.0 43533.0 43820.8 43850.7 43875.8 43974.3 1387.5 1396.4 1412.6 1418.0 1424.9 29921.9 30185.3 30582.2 30921.0 31264.7 51851.7 52426.2 52846.5 53032.0 53146.9 619599.0 620337.0 625711.0 626600.0 627711.9 27340.2 27680.0 27925.2 27998.6 28069.3 36973.8 47739.0 48097.0 48479.0 48575.8 39503.0 40314.8 40625.0 40902.0 41442.2 33582.7 39459.4 39687.4 39888.4 40709.0 38471.6 38641.8 38974.0 39039.0 39373.2 33354.8 35057.3 35372.5 35423.6 35503.0 23297.5 26484.0 26674.2 26749.0 26762.1 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:36186.66 Median:12028.16 stddev:104320.30 (867.3%) stddev-sans-first:104578.30 20:08:15 INFO - PROCESS | 1639 | Values: 379.4 383.2 389.8 397.4 412.0 19322.9 20897.3 20926.2 21568.8 21783.8 20266.4 20337.7 20488.2 20542.5 20613.8 4980.7 5805.3 5984.9 6034.7 6049.4 5147.7 5875.3 5975.8 5987.7 6089.1 5739.9 5872.4 5926.4 5934.6 5954.5 4952.9 5215.6 5880.7 5947.5 5989.5 4505.5 5146.6 5156.2 5271.7 5279.7 20086.3 24430.0 25850.0 26046.9 26269.9 2032.9 2272.5 2296.8 2376.4 2563.8 10130.8 10173.0 10193.4 10471.5 10472.3 1876.9 1979.2 2012.3 2019.4 2026.3 111195.0 126680.1 131845.0 135549.0 137061.9 9485.7 11467.9 11738.8 11882.6 11900.4 14854.8 14998.3 15024.9 15042.5 15227.3 8488.7 8704.2 8810.6 8860.4 8900.6 8567.3 8629.4 8685.3 8790.6 8795.6 3757.3 4638.9 4645.9 4669.1 4731.3 1953.4 1974.1 2018.0 2020.3 2021.6 871.3 952.5 1006.0 1021.7 1047.3 17196.9 17277.0 21163.4 21275.6 22837.8 957.2 967.9 998.4 1015.0 1060.9 10843.7 11104.3 11106.5 11177.3 11181.3 611570.0 620730.0 629682.0 630459.0 633428.3 84688.2 84793.0 137728.0 137831.0 138513.3 994.8 1026.4 1035.1 1056.7 1059.0 19354.8 19663.0 19893.5 20010.9 20083.5 50200.7 55436.7 57947.0 58048.7 60667.4 85514.6 88079.1 88221.0 88427.6 89277.0 7222.9 8138.6 8226.3 8299.0 8372.3 11015.6 13290.4 13309.6 13326.7 13341.7 11141.3 11424.0 11525.4 11644.7 11830.4 11031.7 11190.2 11278.2 11487.4 11584.8 11633.0 11688.3 11869.6 11946.4 12142.6 8239.5 8393.7 8514.4 8527.4 8590.2 8464.6 10832.5 11548.9 11597.2 11836.0 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5413.62 Median:4996.75 stddev:2918.12 (58.4%) stddev-sans-first:2910.32 20:08:15 INFO - PROCESS | 1639 | Values: 1349.6 1386.2 1661.4 1815.7 1877.6 7620.3 7839.0 8049.7 8538.7 8567.8 8024.8 8258.7 8268.6 8389.0 8399.8 2331.8 2351.0 2363.8 2366.1 2376.4 2371.0 2397.6 2418.7 2422.6 2426.4 2351.8 2358.7 2373.7 2374.1 2374.8 2323.4 2325.4 2330.3 2344.4 2346.6 2358.4 2364.0 2368.5 2375.1 2382.9 8176.6 8338.0 8397.2 8406.2 8413.8 4345.4 5231.7 5252.4 5252.9 5257.7 3518.7 3527.2 3572.8 3594.3 3660.5 1079.4 1094.9 1110.2 1123.0 1131.4 9195.3 10702.8 10731.2 10778.0 10802.9 4273.2 4429.6 4517.2 4521.3 4550.8 8519.5 8660.4 8663.7 8681.2 8690.7 5142.3 5153.2 5160.1 5160.7 5183.8 4373.6 4474.0 4675.3 4786.2 4806.1 3141.7 3183.5 3681.5 3789.9 3817.5 1157.7 1169.1 1169.3 1173.5 1174.7 793.6 826.9 904.8 910.7 937.6 5516.4 5556.9 5680.3 5689.7 5831.1 864.9 1025.1 1038.4 1039.5 1057.7 3296.9 3723.1 3829.8 3855.2 3865.9 9742.3 9995.0 10080.8 10110.3 10113.7 10012.8 10089.6 10101.9 10119.9 10149.1 7527.7 7747.1 7944.0 7955.9 7960.2 5664.7 5684.7 5840.6 5870.6 5904.1 9004.4 9109.1 9124.7 9312.7 9382.2 7988.6 9307.8 9330.7 9363.4 9482.1 6599.1 6802.8 7184.3 7280.0 7549.2 7817.4 7949.1 8035.0 8043.2 8062.5 7095.4 7136.6 7286.6 7327.1 7355.4 6089.8 7024.5 7053.4 7264.1 7267.9 6673.9 6722.4 6752.2 6795.3 6807.8 4783.8 4849.9 4899.9 4935.4 4978.4 4235.8 4860.7 4873.5 4981.4 5012.1 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4501.55 Median:5109.86 stddev:1399.93 (27.4%) stddev-sans-first:1367.65 20:08:15 INFO - PROCESS | 1639 | Values: 287.4 305.7 313.9 318.5 319.6 5433.9 5444.4 5461.6 5537.1 5557.5 4449.9 4640.0 4961.6 5380.7 5407.2 3687.9 4160.6 4173.4 4182.6 4215.6 3342.8 3665.0 3871.2 4018.8 4187.9 3559.4 3747.6 4067.2 4115.7 4116.9 3422.1 4101.8 4106.8 4111.0 4129.1 3026.7 3580.6 3595.3 3622.8 3634.9 4261.9 4790.4 5103.6 5172.3 5178.4 4089.9 4697.4 4715.6 4719.6 4739.5 4717.2 4798.6 5431.5 5654.4 5709.8 3023.9 3583.5 3630.8 3910.7 3980.5 5264.3 5337.8 5451.4 5779.5 5987.7 2970.5 3981.0 3983.2 3986.0 3998.9 3723.7 3919.9 4010.3 4032.1 4042.6 4251.4 4279.7 4607.5 4653.9 4686.9 5743.0 5748.3 5757.2 5769.6 5773.5 1185.6 1231.2 1238.7 1286.8 1297.9 816.9 836.1 847.3 875.3 900.0 5218.5 5238.8 5269.6 5272.9 5286.6 4775.3 5116.2 5306.7 5644.2 5689.7 5124.5 5164.4 5178.2 5200.8 5202.0 2465.0 2642.9 2667.9 2927.0 2929.9 5046.9 5534.5 5659.3 5702.5 5798.8 4628.0 4904.8 5276.9 5668.7 5719.1 4983.0 5092.3 5716.2 5765.9 5784.3 4021.6 5300.8 5533.3 5554.4 5568.0 4512.7 4643.0 5474.6 5477.8 5479.2 4265.3 5736.9 5771.5 5791.8 5806.4 3490.8 3933.2 3977.7 3996.3 3998.1 5893.9 5930.8 5989.9 6016.2 6086.1 4910.2 5545.4 5738.5 5842.9 5867.4 4992.7 5727.1 5743.6 5790.3 5812.9 5216.5 5428.6 5527.4 5679.8 5684.4 5579.7 5611.0 5731.5 5731.8 5866.4 4671.6 5161.2 5266.7 5310.5 5560.9 20:08:15 INFO - PROCESS | 1639 | 20:08:15 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21621.31 Median:3870.93 stddev:32629.88 (842.9%) stddev-sans-first:32683.28 20:08:15 INFO - PROCESS | 1639 | Values: 611.3 706.4 776.8 793.1 825.7 13132.1 14269.9 15201.5 15214.5 15660.5 12234.9 12499.2 13605.5 14184.4 14204.9 831.5 847.2 851.3 867.5 871.6 685.8 710.4 828.6 858.6 860.3 896.9 905.0 914.8 917.6 917.6 874.6 908.5 912.2 913.7 914.3 648.4 657.2 674.2 678.7 678.9 14943.9 15257.4 15346.4 15504.3 15572.0 14024.2 14422.7 14464.1 14529.7 14550.8 11882.0 11946.2 11958.2 12000.9 12157.9 3759.8 3779.4 3799.4 3820.7 3863.3 94949.5 99180.5 99843.5 102355.0 102486.0 10483.8 10529.0 10750.2 10918.2 10964.1 17716.8 21279.9 21289.5 21315.4 21340.9 7481.5 7771.0 7891.5 7915.7 8090.7 3878.6 4398.7 4434.2 4511.4 4544.3 3276.2 3353.1 3380.4 3384.4 3387.3 630.3 630.3 643.9 645.6 650.4 536.9 585.7 586.9 596.4 602.9 7155.6 8529.4 8535.4 8618.5 8775.5 593.7 614.3 628.4 641.5 646.0 4017.8 4198.7 4210.5 4322.4 4331.4 113688.0 113830.4 117063.0 119636.0 120165.0 103962.5 104727.0 104915.0 106321.5 106520.0 84775.0 86648.1 88017.6 88082.6 90779.5 8172.5 8273.8 8425.5 8545.8 8559.5 56836.4 57125.0 58100.4 58362.7 59282.0 65658.0 71946.3 85161.6 85175.6 89226.6 10099.8 10401.8 10521.3 10555.0 10603.3 11684.3 11717.9 11895.8 11961.2 12276.6 11060.2 11367.2 13133.0 13360.6 13607.0 13124.9 13540.4 13703.5 13719.6 13740.4 11706.8 12679.7 12796.4 13086.4 13138.1 11110.3 11315.5 11486.1 11663.0 11774.1 9169.9 10249.5 11470.7 11484.9 11489.2 20:08:15 INFO - PROCESS | 1639 | -------- Summary: end -------- 20:08:15 INFO - PROCESS | 1639 | 20:08:16 INFO - TEST-INFO | 1639: exit 0 20:08:16 INFO - TEST-OK | dromaeo_css | took 1114658ms 20:08:16 INFO - TEST-START | kraken 20:08:16 INFO - operating with platform_type : mac_ 20:08:16 INFO - Initialising browser for kraken test... 20:08:16 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpuBJoaL/profile http://localhost:49239/getInfo.html) 20:08:19 INFO - PROCESS | 1650 | 2017-01-24 20:08:19.178 plugin-container[1652:9900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:08:19 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:08:19 INFO - PROCESS | 1650 | 2017-01-24 20:08:19.179 plugin-container[1652:9900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891f, name = 'com.apple.CFPasteboardClient' 20:08:19 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:08:19 INFO - PROCESS | 1650 | 2017-01-24 20:08:19.179 plugin-container[1652:9900] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:08:19 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 20:08:19 INFO - PROCESS | 1650 | colorDepth:24 20:08:19 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 20:08:19 INFO - PROCESS | 1650 | __metrics 20:08:19 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 20:08:20 INFO - TEST-INFO | 1650: exit 0 20:08:20 INFO - Browser initialized. 20:08:20 INFO - Running cycle 1/1 for kraken test... 20:08:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpuBJoaL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:08:22 INFO - PROCESS | 1653 | 2017-01-24 20:08:22.553 plugin-container[1654:10020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 20:08:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:08:22 INFO - PROCESS | 1653 | 2017-01-24 20:08:22.554 plugin-container[1654:10020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 20:08:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:08:22 INFO - PROCESS | 1653 | 2017-01-24 20:08:22.554 plugin-container[1654:10020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:08:32 INFO - PROCESS | 1653 | RSS: Main: 177037312 20:08:32 INFO - PROCESS | 1653 | 20:08:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 20:08:35 INFO - PROCESS | 1653 | RSS: Main: 189890560 20:08:35 INFO - PROCESS | 1653 | 20:08:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 20:08:39 INFO - PROCESS | 1653 | RSS: Main: 197804032 20:08:39 INFO - PROCESS | 1653 | 20:08:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 20:08:43 INFO - PROCESS | 1653 | RSS: Main: 201695232 20:08:43 INFO - PROCESS | 1653 | 20:08:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 20:08:45 INFO - PROCESS | 1653 | RSS: Main: 203034624 20:08:45 INFO - PROCESS | 1653 | 20:08:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:08:48 INFO - PROCESS | 1653 | RSS: Main: 203010048 20:08:48 INFO - PROCESS | 1653 | 20:08:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 20:08:51 INFO - PROCESS | 1653 | RSS: Main: 207036416 20:08:51 INFO - PROCESS | 1653 | 20:08:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 20:08:55 INFO - PROCESS | 1653 | RSS: Main: 207589376 20:08:55 INFO - PROCESS | 1653 | 20:08:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 20:08:58 INFO - PROCESS | 1653 | RSS: Main: 206995456 20:08:58 INFO - PROCESS | 1653 | 20:09:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:09:01 INFO - PROCESS | 1653 | RSS: Main: 205832192 20:09:01 INFO - PROCESS | 1653 | 20:09:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:09:03 INFO - PROCESS | 1653 | RSS: Main: 205881344 20:09:03 INFO - PROCESS | 1653 | 20:09:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:09:06 INFO - PROCESS | 1653 | RSS: Main: 205905920 20:09:06 INFO - PROCESS | 1653 | 20:09:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:09:10 INFO - PROCESS | 1653 | RSS: Main: 205942784 20:09:10 INFO - PROCESS | 1653 | 20:09:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:09:13 INFO - PROCESS | 1653 | RSS: Main: 206319616 20:09:13 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 20:09:15 INFO - PROCESS | 1653 | RSS: Main: 208060416 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | __start_tp_report 20:09:15 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 20:09:15 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 20:09:15 INFO - PROCESS | 1653 | |i|pagename|runs| 20:09:15 INFO - PROCESS | 1653 | |0;ai-astar;116;121;125;171;163;119;116;117;117;118 20:09:15 INFO - PROCESS | 1653 | |1;audio-beat-detection;143;132;100;158;144;147;138;110;137;109 20:09:15 INFO - PROCESS | 1653 | |2;audio-dft;196;205;196;235;282;207;197;201;197;195 20:09:15 INFO - PROCESS | 1653 | |3;audio-fft;82;57;58;83;63;72;83;60;77;59 20:09:15 INFO - PROCESS | 1653 | |4;audio-oscillator;83;75;89;73;69;71;84;71;69;86 20:09:15 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;93;90;91;89;91;89;93;90;98;89 20:09:15 INFO - PROCESS | 1653 | |6;imaging-darkroom;98;98;97;98;150;151;97;97;109;150 20:09:15 INFO - PROCESS | 1653 | |7;imaging-desaturate;88;94;124;135;128;90;91;81;127;134 20:09:15 INFO - PROCESS | 1653 | |8;json-parse-financial;56;57;56;59;66;87;49;51;64;84 20:09:15 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;70;104;99;85;83;74;70;74;72;70 20:09:15 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;82;82;84;84;85;84;86;87;84;82 20:09:15 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;144;154;148;136;143;145;137;149;208;164 20:09:15 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;163;185;170;176;168;166;165;168;158;226 20:09:15 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;67;65;68;67;67;64;66;66 20:09:15 INFO - PROCESS | 1653 | __end_tp_report 20:09:15 INFO - PROCESS | 1653 | __start_cc_report 20:09:15 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,914 20:09:15 INFO - PROCESS | 1653 | __end_cc_report 20:09:15 INFO - PROCESS | 1653 | __startTimestamp1485317355671__endTimestamp 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | ------- Summary: start ------- 20:09:15 INFO - PROCESS | 1653 | Number of tests: 14 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:128.30 Median:120.00 stddev:20.66 (17.2%) stddev-sans-first:21.43 20:09:15 INFO - PROCESS | 1653 | Values: 116.0 121.0 125.0 171.0 163.0 119.0 116.0 117.0 117.0 118.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:140.50 stddev:19.04 (13.6%) stddev-sans-first:19.76 20:09:15 INFO - PROCESS | 1653 | Values: 143.0 132.0 100.0 158.0 144.0 147.0 138.0 110.0 137.0 109.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:211.10 Median:203.00 stddev:27.62 (13.6%) stddev-sans-first:28.75 20:09:15 INFO - PROCESS | 1653 | Values: 196.0 205.0 196.0 235.0 282.0 207.0 197.0 201.0 197.0 195.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:69.40 Median:74.50 stddev:11.13 (14.9%) stddev-sans-first:10.83 20:09:15 INFO - PROCESS | 1653 | Values: 82.0 57.0 58.0 83.0 63.0 72.0 83.0 60.0 77.0 59.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:79.00 stddev:7.67 (9.7%) stddev-sans-first:7.83 20:09:15 INFO - PROCESS | 1653 | Values: 83.0 75.0 89.0 73.0 69.0 71.0 84.0 71.0 69.0 86.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:91.00 stddev:2.79 (3.1%) stddev-sans-first:2.89 20:09:15 INFO - PROCESS | 1653 | Values: 93.0 90.0 91.0 89.0 91.0 89.0 93.0 90.0 98.0 89.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:114.50 Median:97.00 stddev:24.99 (25.8%) stddev-sans-first:25.78 20:09:15 INFO - PROCESS | 1653 | Values: 98.0 98.0 97.0 98.0 150.0 151.0 97.0 97.0 109.0 150.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:84.50 stddev:21.97 (26.0%) stddev-sans-first:21.93 20:09:15 INFO - PROCESS | 1653 | Values: 88.0 94.0 124.0 135.0 128.0 90.0 91.0 81.0 127.0 134.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:61.50 stddev:12.98 (21.1%) stddev-sans-first:13.53 20:09:15 INFO - PROCESS | 1653 | Values: 56.0 57.0 56.0 59.0 66.0 87.0 49.0 51.0 64.0 84.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:74.00 stddev:12.50 (16.9%) stddev-sans-first:12.72 20:09:15 INFO - PROCESS | 1653 | Values: 70.0 104.0 99.0 85.0 83.0 74.0 70.0 74.0 72.0 70.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.70 (2.0%) stddev-sans-first:1.64 20:09:15 INFO - PROCESS | 1653 | Values: 82.0 82.0 84.0 84.0 85.0 84.0 86.0 87.0 84.0 82.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:148.50 stddev:21.02 (14.2%) stddev-sans-first:22.06 20:09:15 INFO - PROCESS | 1653 | Values: 144.0 154.0 148.0 136.0 143.0 145.0 137.0 149.0 208.0 164.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:169.00 stddev:19.54 (11.6%) stddev-sans-first:20.28 20:09:15 INFO - PROCESS | 1653 | Values: 163.0 185.0 170.0 176.0 168.0 166.0 165.0 168.0 158.0 226.0 20:09:15 INFO - PROCESS | 1653 | 20:09:15 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 20:09:15 INFO - PROCESS | 1653 | Values: 67.0 66.0 67.0 65.0 68.0 67.0 67.0 64.0 66.0 66.0 20:09:15 INFO - PROCESS | 1653 | -------- Summary: end -------- 20:09:15 INFO - PROCESS | 1653 | 20:09:16 INFO - TEST-INFO | 1653: exit 0 20:09:16 INFO - TEST-OK | kraken | took 60398ms 20:09:16 INFO - SUITE-END | took 1175s 20:09:16 INFO - Completed test suite (00:19:35) 20:09:16 INFO - javascript benchmark 20:09:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7295.465255666976, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3226.032190342897, 3598.4242521196597, 3606.0984753811554, 3623.18478342373, 3627.0, 5891.528677705784, 5964.79123230687, 5986.341502434734, 5986.9101963470475, 6008.549358798085, 4522.525134860842, 5853.76584936602, 5953.255843019622, 6015.548833837458, 6041.728122234499, 5405.756740946657, 5468.179773034057, 5496.31296087989, 5512.255845460852, 5523.171524271372, 5433.891322173554, 5481.972590137064, 5528.5024347808685, 5529.419410961851, 5553.583481238903, 5389.137737961927, 5414.1608050752, 5517.144842549411, 5536.086545719952, 5574.637648552831, 5463.07127788273, 5488.067028605146, 5504.559635229192, 5507.311586051744, 5518.310211223597, 4580.702254353457, 5340.54605358545, 5481.808136715196, 5485.533729632986, 5540.667559946416, 5933.376995415462, 5975.25309336333, 6076.754265375587, 6078.483328917047, 6098.664573448458, 5540.9168862467095, 6222.906656400158, 6289.182406287199, 6312.116303717483, 6342.587731797465, 6266.4360207581085, 6301.054841773725, 6306.463950564207, 6313.936860631381, 6347.936520634781, 6097.115918191837, 6098.817035488943, 6102.237220347457, 6109.022556390956, 6119.112728654321, 5540.252065971123, 6321.051842223611, 6453.612783233021, 6537.934620653733, 6550.443212326911, 6334.778282760081, 6338.936610633927, 6351.61890286575, 6354.4281014708895, 6367.7452901884335, 6342.175517182736, 6346.365363463709, 6365.809025729143, 6366.395192456709, 6373.0, 6234.220722409699, 6280.309165991737, 6300.180976473029, 6300.9684951574945, 6313.05304204373, 6303.3066362700065, 6318.873622527615, 6319.55763096588, 6322.620642761357, 6329.113924050636, 5115.411727651293, 5117.7717347837, 5122.692638441706, 5124.948750512522, 5134.897302053938, 4510.72935623857, 4520.434945631796, 4563.06457176278, 4595.333676616872, 4602.0, 5313.229581710631, 5541.889162216733, 6229.719662615194, 6243.344448832898, 6269.404406581368, 6361.4592759615025, 6379.425851673372, 6383.202099737533, 6387.904181437282, 6397.040443933354, 6102.115193296947, 6141.516692814271, 6161.291451483047, 6194.628322300677, 6206.317305096436, 5626.943730562723, 5632.155176723442, 5633.521150702154, 5679.977604031314, 5692.345380281238, 6505.024246363083, 6578.8355291118105, 6583.670816459158, 6609.504287178481, 6615.768448104293, 6193.535484838565, 6210.5031597473, 6266.624002559861, 6373.553851230555, 6376.872462550816, 6457.7094030767785, 6571.112899758471, 6572.079908812866, 6580.736770529125, 6583.473322134314, 6340.619562826245, 6347.5556711029785, 6359.395857393541, 6373.936260637334, 6388.0737293091315, 6142.57002009855, 6282.780102696566, 6330.556861019684, 6332.651704156316, 6358.809235722936, 6243.937560624336, 6251.937480625317, 6264.812055638338, 6345.143405640364, 6347.96826015867, 5372.262193425296, 5718.456746609066, 6250.406211409909, 6257.749690012349, 6378.138951241709, 6063.9696801517475, 6174.197354343906, 6210.099535567408, 6234.719437625227, 6244.843878903063, 6209.59637623553, 6238.970569856105, 6246.156768836331, 6292.0, 6320.905186422116, 5056.949430505648, 5178.948210517847, 6200.7519699213335, 6275.309715931153, 6279.309275979547], "value": 5871.390180217121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.4167103600837, 523.7800123947942, 539.7085573790154, 543.8178210299546, 543.8232574413316, 40504.797476012616, 42558.787206063964, 42641.57358426419, 44065.339019914725, 44290.89272768182, 42270.788646056804, 42906.57093429065, 42947.78526107373, 42960.0, 43176.0, 9726.902730972688, 9854.605815767396, 9855.605775768996, 9860.21118310537, 9874.210063194927, 9748.0, 9765.169960553361, 9862.0, 9867.654632087879, 9878.456684882329, 9768.755781105494, 9842.557084931159, 9884.505774711271, 9902.356346837469, 9919.801603967917, 9810.018998100204, 9844.114029737359, 9868.851967220497, 9872.851907221397, 9878.259130565164, 9696.0, 9746.610135594568, 9753.21974242059, 9755.853662195073, 9788.119069283728, 40454.79772601148, 40734.59265407338, 40786.79606601978, 41220.76337709874, 41234.0, 3325.5510506081855, 3485.7385696072897, 3654.451832225161, 3900.570937196906, 4128.236276288896, 30388.0, 30700.2324941876, 30886.845565772026, 31674.0, 31900.521492177635, 3204.7436205103536, 3294.884679036222, 3300.1089705779304, 3357.0, 3366.4950257461333, 375227.12386437895, 377900.1104994485, 379840.0, 379950.0, 380248.0, 62293.37706622876, 62340.0, 62740.05889911655, 62830.0, 63158.0526292106, 64692.029619555746, 65133.02300465497, 65256.0, 65256.67371663206, 65352.34647653463, 16730.6653866922, 16859.662806743796, 17029.659406811796, 17034.06312652791, 17076.80462367647, 12891.871081289255, 13073.346332683328, 13179.341032948314, 13183.011274154476, 13184.472621095241, 16200.108994005208, 17287.913560432116, 17715.645687086188, 17787.46637600874, 17917.477014453656, 2798.496270671258, 6660.8667826643195, 6874.621895795679, 6903.689333979983, 6912.585244885323, 845.9407841451163, 926.8424367857481, 958.1807554540907, 1028.9125424338865, 1075.4891426572472, 30430.0, 30535.847320763252, 30673.62664678101, 30909.227269318, 31028.53457198144, 895.9114675669093, 959.3140904253493, 977.193815102529, 1070.117153348475, 1112.9220954533105, 14669.853301466848, 16043.278052487665, 16116.75824862628, 16149.919250403907, 16421.42525011619, 696046.0, 701460.0, 702168.0, 702423.0, 703360.0, 43533.0, 43820.78089609532, 43850.68447946503, 43875.78062109669, 43974.340384894254, 1387.486629946926, 1396.3576754692751, 1412.639776856895, 1417.9716405672036, 1424.921629310398, 29921.850390747906, 30185.32231372125, 30582.235444114074, 30921.0, 31264.687353126177, 51851.703707407614, 52426.213606795165, 52846.471535285695, 53032.0, 53146.937061257784, 619599.0, 620337.0, 625711.0, 626600.0, 627711.8614406899, 27340.1797946054, 27680.0, 27925.16224513268, 27998.580021300106, 28069.298267543476, 36973.81513092525, 47739.0, 48097.03805923932, 48479.0, 48575.78560536015, 39503.0, 40314.798426007685, 40625.0, 40901.97745056397, 41442.1711565773, 33582.66417335795, 39459.40810887782, 39687.40468893027, 39888.40167397433, 40709.0, 38471.615283846804, 38641.806790965864, 38974.0, 39039.0, 39373.21253574855, 33354.81196209787, 35057.298854023284, 35372.46941295934, 35423.64576354204, 35503.0, 23297.534049319256, 26484.0, 26674.24571060979, 26749.0, 26762.063327783435], "value": 20022.020487757247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.356989902116, 383.1876421985391, 389.77977442744844, 397.4455634390028, 411.9670426365891, 19322.90338548307, 20897.26859559916, 20926.162953481842, 21568.784312156873, 21783.801890895993, 20266.392008239738, 20337.694934575993, 20488.18047278107, 20542.486437839063, 20613.793862061375, 4980.676256043345, 5805.303363596364, 5984.8503787405225, 6034.668093254869, 6049.395060493959, 5147.7426128693605, 5875.265591801025, 5975.790847320344, 5987.730552125143, 6089.056196289582, 5739.885202295973, 5872.442117998783, 5926.436988486087, 5934.5845790794665, 5954.4938680212235, 4952.925706114411, 5215.556677682363, 5880.7059647017695, 5947.494462970652, 5989.490893274077, 4505.5269196734525, 5146.562542183919, 5156.226566015091, 5271.736413179364, 5279.70961597112, 20086.296979605642, 24430.0, 25849.966001359924, 26046.86976565124, 26269.949202031898, 2032.9085191166432, 2272.522770218256, 2296.7588403217587, 2376.3821406434276, 2563.7564431378987, 10130.797384052277, 10172.982701729768, 10193.439360835151, 10471.528781204865, 10472.26694131411, 1876.8967706776123, 1979.2478858033999, 2012.3359291433937, 2019.3840878532042, 2026.290798220611, 111195.0, 126680.0997985031, 131845.0, 135549.0, 137061.94407084002, 9485.66800161991, 11467.942660286812, 11738.76522469563, 11882.643520694393, 11900.404979750978, 14854.777178342334, 14998.325075371635, 15024.924875375553, 15042.548723538312, 15227.314770835339, 8488.74533763988, 8704.216620504185, 8810.559472026373, 8860.424072435271, 8900.643974241088, 8567.271781898484, 8629.352798540134, 8685.348598855111, 8790.560471976376, 8795.560221988875, 3757.323681737259, 4638.884027899262, 4645.883852903637, 4669.112868554964, 4731.313959475926, 1953.4335042837702, 1974.1017836884182, 2018.0, 2020.3130935481713, 2021.6158929803291, 871.2724874729672, 952.4713783849892, 1005.958832608247, 1021.7241344836852, 1047.2878442659064, 17196.914015429844, 17277.0, 21163.40222162236, 21275.574488510145, 22837.771622283566, 957.1624828275259, 967.908048735369, 998.3710262534556, 1014.9847752283722, 1060.867391576053, 10843.728906777236, 11104.333739975627, 11106.500207490682, 11177.32936023825, 11181.273217240854, 611570.0, 620730.0, 629682.0, 630459.0, 633428.3314333687, 84688.15311846926, 84793.0, 137728.0, 137831.0, 138513.30743346422, 994.8213412626325, 1026.3585259212844, 1035.0839507036328, 1056.6829951014818, 1058.9841152382721, 19354.806451935863, 19663.0, 19893.502662433555, 20010.89994550018, 20083.497912552302, 50200.748996254784, 55436.72281638566, 57947.0, 58048.709756450946, 60667.39332606617, 85514.57242713746, 88079.1192088071, 88221.0, 88427.55786221028, 89277.0, 7222.855542889218, 8138.593070346341, 8226.30076443485, 8299.0, 8372.28835548985, 11015.614453494089, 13290.401931912893, 13309.600711978655, 13326.733465330833, 13341.733165336833, 11141.331520108819, 11424.0, 11525.366104864313, 11644.708882278012, 11830.40847957616, 11031.724206894893, 11190.160737944523, 11278.210525263154, 11487.425628718363, 11584.826227606423, 11633.0, 11688.298702077902, 11869.643910682693, 11946.402679865798, 12142.575009874612, 8239.475696995834, 8393.664253429795, 8514.361422893184, 8527.445716028438, 8590.226879580869, 8464.57677116154, 10832.458377080957, 11548.884511154782, 11597.246178998337, 11836.0], "value": 9737.111467491894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.6423447786335, 1386.2029333133448, 1661.4434164554873, 1815.7276408538726, 1877.586930875207, 7620.3141717245435, 7839.0, 8049.678012879485, 8538.658453661854, 8567.828643427136, 8024.839503209932, 8258.710945116922, 8268.586570671458, 8389.0, 8399.832003359928, 2331.8367714259925, 2350.976490235101, 2363.8463499872455, 2366.124533922451, 2376.3583832365252, 2370.9762902371012, 2397.6283676030243, 2418.661387405765, 2422.5518279118332, 2426.4419183587784, 2351.835371523994, 2358.716953965518, 2373.6558199061124, 2374.1097088591778, 2374.774396432345, 2323.372689373868, 2325.4070212095808, 2330.2776139396674, 2344.4138965258685, 2346.554154710608, 2358.3750306168877, 2363.988180059089, 2368.4789346343778, 2375.0737212487143, 2382.8808559571953, 8176.550289734062, 8337.95831020841, 8397.160283971614, 8406.159384061606, 8413.83172336556, 4345.435093437864, 5231.659942103751, 5252.448492908228, 5252.8686782830355, 5257.737113144366, 3518.665726755954, 3527.2416430467315, 3572.8392222350058, 3594.263176048904, 3660.524131862841, 1079.4440862955585, 1094.8795632480421, 1110.156281226274, 1122.9887701122966, 1131.389049913046, 9195.264378849675, 10702.839457408145, 10731.248812583122, 10777.976092271221, 10802.891971080267, 4273.166732487197, 4429.579189976947, 4517.164324599959, 4521.299198624219, 4550.772461376984, 8519.48883067018, 8660.350473714496, 8663.74008779725, 8681.175288347597, 8690.739277821676, 5142.331496905454, 5153.2270159476375, 5160.071187186268, 5160.690358578498, 5183.766730497136, 4373.628241599499, 4473.955260447419, 4675.345451636774, 4786.1624215762795, 4806.086843499724, 3141.701538353853, 3183.4588120019653, 3681.4661874028116, 3789.9431508527396, 3817.541894972621, 1157.65270418874, 1169.0939521870619, 1169.2633640806237, 1173.4543437301695, 1174.7004513848915, 793.6031984007996, 826.9173082691683, 904.841652710773, 910.6812615584493, 937.6155776131753, 5516.365617953907, 5556.91664625031, 5680.318361796611, 5689.6586204827845, 5831.125331200269, 864.9221570058725, 1025.1183981775666, 1038.4392428088747, 1039.542601255445, 1057.6509751781894, 3296.9340613188083, 3723.1436769543157, 3829.827657755352, 3855.190410013872, 3865.864694735727, 9742.269329800152, 9995.000499950092, 10080.848787268196, 10110.292279540508, 10113.69658910234, 10012.849807252896, 10089.646862359783, 10101.949490252502, 10119.949400252952, 10149.137323327452, 7527.698892044257, 7747.109082455477, 7944.046714394194, 7955.880661790078, 7960.203979602109, 5664.660120392789, 5684.744186511534, 5840.620359676607, 5870.647761134345, 5904.1143828424365, 9004.41471304363, 9109.089091090838, 9124.680636177967, 9312.720618381189, 9382.202512786489, 7988.560629165451, 9307.813843723223, 9330.673426430043, 9363.4381937084, 9482.146606805421, 6599.076129341992, 6802.795916122524, 7184.281571842774, 7279.980802687734, 7549.245075492627, 7817.37461003107, 7949.085855126735, 8035.0, 8043.1554686757045, 8062.475939063942, 7095.361417472453, 7136.643167841691, 7286.635668216462, 7327.120745510573, 7355.448341374311, 6089.756409743561, 7024.473164512784, 7053.365197132282, 7264.128304603481, 7267.854642907007, 6673.866522669617, 6722.394984451423, 6752.155980502437, 6795.25252222265, 6807.761728339485, 4783.808647654055, 4849.927251091307, 4899.902001959869, 4935.432425271139, 4978.352814134139, 4235.809388577583, 4860.684055536521, 4873.4882837301575, 4981.37732783402, 5012.122878496176], "value": 4418.819579454145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.3835622589545, 305.7401208972375, 313.8716314848121, 318.5079052863326, 319.5765610565999, 5433.9298296462475, 5444.373897001846, 5461.563074954004, 5537.141743029832, 5557.499825015742, 4449.888752781182, 4640.0, 4961.578265847407, 5380.663988327233, 5407.215953686723, 3687.944680829776, 4160.625543701074, 4173.415721798935, 4182.644475219595, 4215.641670458, 3342.8328583570847, 3664.9633503665023, 3871.2257548490416, 4018.819153138116, 4187.937180942273, 3559.3771090059267, 3747.625237476258, 4067.247559201557, 4115.732477388975, 4116.91766164678, 3422.144463884029, 4101.794910254475, 4106.77412742299, 4111.013356794363, 4129.050318426749, 3026.7124623160767, 3580.5703315602295, 3595.2989167112423, 3622.7826330420257, 3634.945475817865, 4261.850835220784, 4790.4251489821445, 5103.56619687327, 5172.301739265226, 5178.430372659004, 4089.9182016359505, 4697.365855609483, 4715.646326525524, 4719.575238228542, 4739.502352252998, 4717.1980763269985, 4798.616110711135, 5431.456854314576, 5654.434556544354, 5709.800156994485, 3023.9244018899485, 3583.498310236569, 3630.7640003399692, 3910.7066969977363, 3980.4825372701653, 5264.31563896691, 5337.759800808973, 5451.427600101972, 5779.450952159538, 5987.700614969234, 2970.5395663672166, 3980.980095099506, 3983.24318379507, 3986.0, 3998.8600398985895, 3723.72072094594, 3919.902427320355, 4010.2981978153707, 4032.052467670095, 4042.555318914917, 4251.427886128909, 4279.7218180818145, 4607.493175750665, 4653.86038418848, 4686.906261874743, 5743.023685973311, 5748.281464816898, 5757.193992841006, 5769.624974376652, 5773.480386765127, 1185.6206014075412, 1231.150506150753, 1238.7336722604502, 1286.8354139503765, 1297.8831905128584, 816.8645582640135, 836.0803116571723, 847.2967437027253, 875.2560323724783, 900.0009988912302, 5218.503602586276, 5238.816641417532, 5269.631125821155, 5272.894542109137, 5286.550643195372, 4775.2598347256235, 5116.155834287301, 5306.681599103988, 5644.186487917452, 5689.6586204827845, 5124.540220694013, 5164.406093299319, 5178.249153872667, 5200.791968321225, 5201.973990130025, 2464.9770345306497, 2642.947141057168, 2667.906158475016, 2926.956095658567, 2929.868155932988, 5046.949530504721, 5534.4742249486235, 5659.292588426447, 5702.460335709336, 5798.82603521895, 4627.9768601156775, 4904.779284932187, 5276.947230527722, 5668.659880407189, 5719.142128680731, 4982.950170498394, 5092.3335198656905, 5716.228309178207, 5765.875654247381, 5784.334801497882, 4021.5777343378522, 5300.840974770763, 5533.3083364579425, 5554.444555544414, 5568.0, 4512.653420221401, 4642.953570464388, 5474.616776825584, 5477.7814984931165, 5479.205515200353, 4265.293882447046, 5736.885262294814, 5771.538276937918, 5791.8416316735975, 5806.44838740319, 3490.842912068912, 3933.233019561158, 3977.6618987386396, 3996.300647386754, 3998.1004274039274, 5893.852653683693, 5930.822075337746, 5989.940100598938, 6016.187814645142, 6086.1175129606845, 4910.189818679878, 5545.445455454584, 5738.483536481736, 5842.912356314744, 5867.383924688017, 4992.700437973734, 5727.11229759388, 5743.569232307677, 5790.334111577054, 5812.912806307824, 5216.452272511332, 5428.561431220651, 5527.364353099284, 5679.801206957736, 5684.403137670651, 5579.69311687861, 5610.971945140248, 5731.478149614157, 5731.828045158652, 5866.384029676822, 4671.602913752369, 5161.22581612746, 5266.735303819394, 5310.522053015247, 5560.944390556043], "value": 4053.7637946337395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.3061674998876, 706.4030893894658, 776.7553220735468, 793.1037927142328, 825.7440193540001, 13132.146410483323, 14269.857301426982, 15201.467948621801, 15214.467493637725, 15660.451884184056, 12234.93882530586, 12499.187552809084, 13605.523806666768, 14184.432622695105, 14204.857951420483, 831.5301854452246, 847.1697736218525, 851.259404318244, 867.5141920524527, 871.6252011634995, 685.835399504118, 710.3997122431563, 828.6146941672122, 858.6050416808275, 860.3461369359301, 896.9461832290084, 904.9909500904972, 914.7804526913559, 917.5779141594875, 917.5962576466334, 874.641397027219, 908.500324821349, 912.1608120529129, 913.6893456224915, 914.3371055984425, 648.3581254558001, 657.1982181738272, 674.1741366825648, 678.7454704485817, 678.8710145072421, 14943.925280373638, 15257.38970441181, 15346.386144554204, 15504.30230639624, 15572.0, 14024.228667423287, 14422.701956823936, 14464.13215207091, 14529.709405811824, 14550.781738273934, 11882.049436045096, 11946.223495472766, 11958.162928595002, 12000.879991200063, 12157.93921030398, 3759.793211373373, 3779.395296752534, 3799.35410980134, 3820.6752426043818, 3863.2659794639208, 94949.52525237399, 99180.50409747977, 99843.50078249635, 102354.95290094231, 102486.0, 10483.790324193473, 10529.0, 10750.193735469715, 10918.181136414642, 10964.122870170368, 17716.822831771777, 21279.893600532207, 21289.467763305733, 21315.401344899194, 21340.893295533424, 7481.513701609487, 7770.961145194238, 7891.526508409514, 7915.722949696848, 8090.676372945016, 3878.5927477614446, 4398.714083584557, 4434.224010798098, 4511.390962220124, 4544.341070544752, 3276.19733165376, 3353.111425472252, 3380.4084285250474, 3384.4415671414436, 3387.288669379458, 630.2877748144567, 630.3255516597197, 643.8615697625033, 645.631989765829, 650.3756393861851, 536.9355677318747, 585.7276366489602, 586.9325027621878, 596.4363676325917, 602.8523011862122, 7155.642217889084, 8529.402941794015, 8535.359848011423, 8618.479144737175, 8775.473471591726, 593.6912805341199, 614.2690198663576, 628.4438272129198, 641.4964253061418, 645.9838504037343, 4017.8392864285684, 4198.748075115503, 4210.45264115663, 4322.438083049184, 4331.417406009646, 113688.0, 113830.43084784689, 117063.0, 119636.0, 120165.0, 103962.48018759857, 104726.95273047172, 104915.0, 106321.46839265754, 106520.0, 84775.0, 86648.13351866527, 88017.55991220131, 88082.55958720166, 90779.54610227038, 8172.530082014571, 8273.83452330962, 8425.4944703318, 8545.786355341166, 8559.486430814171, 56836.43163568311, 57125.0, 58100.41899581119, 58362.708186458796, 59282.0, 65658.0, 71946.28053719396, 85161.57419212864, 85175.574122129, 89226.55386723025, 10099.848502272618, 10401.791964160522, 10521.27739136926, 10555.0, 10603.257771956198, 11684.298942063504, 11717.941410292893, 11895.821562676394, 11961.162718609961, 12276.631701048982, 11060.225784195029, 11367.20429569922, 13133.027134888043, 13360.599182024554, 13606.979476539102, 13124.868751312746, 13540.390682419511, 13703.520376786764, 13719.588412347646, 13740.381682824496, 11706.765864682488, 12679.683007924876, 12796.424160912595, 13086.411111500196, 13138.146020508637, 11110.277831940897, 11315.547378104784, 11486.081113511067, 11663.0, 11774.116941229271, 9169.908300916906, 10249.487525623837, 11470.713232169262, 11484.940624164185, 11489.195756297313], "value": 7352.359086125466, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 121.0, 125.0, 171.0, 163.0, 119.0, 116.0, 117.0, 117.0, 118.0], "value": 128.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 132.0, 100.0, 158.0, 144.0, 147.0, 138.0, 110.0, 137.0, 109.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 196.0, 235.0, 282.0, 207.0, 197.0, 201.0, 197.0, 195.0], "value": 211.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 58.0, 83.0, 63.0, 72.0, 83.0, 60.0, 77.0, 59.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 89.0, 73.0, 69.0, 71.0, 84.0, 71.0, 69.0, 86.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 91.0, 89.0, 91.0, 89.0, 93.0, 90.0, 98.0, 89.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 98.0, 150.0, 151.0, 97.0, 97.0, 109.0, 150.0], "value": 114.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 124.0, 135.0, 128.0, 90.0, 91.0, 81.0, 127.0, 134.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 59.0, 66.0, 87.0, 49.0, 51.0, 64.0, 84.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 104.0, 99.0, 85.0, 83.0, 74.0, 70.0, 74.0, 72.0, 70.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 84.0, 84.0, 85.0, 84.0, 86.0, 87.0, 84.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 154.0, 148.0, 136.0, 143.0, 145.0, 137.0, 149.0, 208.0, 164.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 185.0, 170.0, 176.0, 168.0, 166.0, 165.0, 168.0, 158.0, 226.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 65.0, 68.0, 67.0, 67.0, 64.0, 66.0, 66.0], "value": 66.3, "unit": "score"}]}]} 20:09:16 INFO - Return code: 0 20:09:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:09:16 ERROR - # TBPL SUCCESS # 20:09:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:09:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:09:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:09:16 INFO - 2017-01-24 20:09:16.990 screenresolution[1658:10402] starting screenresolution argv=screenresolution get 20:09:16 INFO - 2017-01-24 20:09:16.993 screenresolution[1658:10402] Display 0: 1600x1200x32@60 20:09:17 INFO - 2017-01-24 20:09:16.999 screenresolution[1659:10405] starting screenresolution argv=screenresolution list 20:09:17 INFO - Available Modes on Display 0 20:09:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:09:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:09:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:09:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:09:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:09:17 INFO - Intel Iris: 20:09:17 INFO - Chipset Model: Intel Iris 20:09:17 INFO - Type: GPU 20:09:17 INFO - Bus: Built-In 20:09:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:09:17 INFO - Vendor: Intel (0x8086) 20:09:17 INFO - Device ID: 0x0a2e 20:09:17 INFO - Revision ID: 0x0009 20:09:17 INFO - Displays: 20:09:17 INFO - Display: 20:09:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:09:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:09:17 INFO - Display Serial Number: 1600x1200 60 20:09:17 INFO - Main Display: Yes 20:09:17 INFO - Mirror: Off 20:09:17 INFO - Online: Yes 20:09:17 INFO - Rotation: Supported 20:09:17 INFO - Return code: 0 20:09:17 INFO - Running post-action listener: _resource_record_post_action 20:09:17 INFO - [mozharness: 2017-01-25 04:09:17.251166Z] Finished run-tests step (success) 20:09:17 INFO - Running post-run listener: _resource_record_post_run 20:09:17 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40183808; Write bytes: 498037248; Read time: 393; Write time: 1510 20:09:17 INFO - TinderboxPrint: CPU usage
54.3% 20:09:17 INFO - TinderboxPrint: I/O read bytes / time
40,183,808 / 393 20:09:17 INFO - TinderboxPrint: I/O write bytes / time
498,037,248 / 1,510 20:09:17 INFO - TinderboxPrint: CPU idle
2,188.7 (45.7%) 20:09:17 INFO - TinderboxPrint: CPU system
972.9 (20.3%) 20:09:17 INFO - TinderboxPrint: CPU user
1,628.1 (34.0%) 20:09:17 INFO - TinderboxPrint: Swap in / out
231,837,696 / 0 20:09:17 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192281088; Write bytes: 192012288; Read time: 17653; Write time: 365 20:09:17 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35801088; Write bytes: 304501248; Read time: 358; Write time: 1138 20:09:17 INFO - Running post-run listener: _upload_blobber_files 20:09:17 INFO - Blob upload gear active. 20:09:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:09:17 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 20:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:09:18 INFO - (blobuploader) - INFO - Open directory for files ... 20:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:18 INFO - (blobuploader) - INFO - Done attempting. 20:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:09:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:19 INFO - (blobuploader) - INFO - Done attempting. 20:09:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:09:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:09:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:22 INFO - (blobuploader) - INFO - Done attempting. 20:09:22 INFO - (blobuploader) - INFO - Iteration through files over. 20:09:22 INFO - Return code: 0 20:09:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:09:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:09:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1467b9940916863c67876d0b3c87b9729940d006c7e3d57590791a6b08e9cb957eb39766d945d47f82be462785ed1865afff9d8860202f184c412268036ea94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcb85376b4892febd023036b76b250797e97d2a961965720d0243c639a04d231290138f7903faece6856e2893e4284527e18dae9e0f3308c47258acd7da769cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a475c3cf2642e1f87fdb8725796fcac646574bfd22dc75a66eeba6cb350d234144326ec07e778601cc114fef444c71365c3e74b3e0990114060a8c6c791b6d1"} 20:09:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:09:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:09:22 INFO - Contents: 20:09:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1467b9940916863c67876d0b3c87b9729940d006c7e3d57590791a6b08e9cb957eb39766d945d47f82be462785ed1865afff9d8860202f184c412268036ea94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcb85376b4892febd023036b76b250797e97d2a961965720d0243c639a04d231290138f7903faece6856e2893e4284527e18dae9e0f3308c47258acd7da769cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a475c3cf2642e1f87fdb8725796fcac646574bfd22dc75a66eeba6cb350d234144326ec07e778601cc114fef444c71365c3e74b3e0990114060a8c6c791b6d1"} 20:09:22 INFO - Running post-run listener: copy_logs_to_upload_dir 20:09:22 INFO - Copying logs to upload dir... 20:09:22 INFO - mkdir: /builds/slave/test/build/upload/logs 20:09:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.875218 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-24 20:09:22.505949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 20:09:22.510087) ========= 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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1467b9940916863c67876d0b3c87b9729940d006c7e3d57590791a6b08e9cb957eb39766d945d47f82be462785ed1865afff9d8860202f184c412268036ea94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcb85376b4892febd023036b76b250797e97d2a961965720d0243c639a04d231290138f7903faece6856e2893e4284527e18dae9e0f3308c47258acd7da769cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a475c3cf2642e1f87fdb8725796fcac646574bfd22dc75a66eeba6cb350d234144326ec07e778601cc114fef444c71365c3e74b3e0990114060a8c6c791b6d1"} build_url:https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/SJuycSH1R1qYuYmJJGSCgw/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1467b9940916863c67876d0b3c87b9729940d006c7e3d57590791a6b08e9cb957eb39766d945d47f82be462785ed1865afff9d8860202f184c412268036ea94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcb85376b4892febd023036b76b250797e97d2a961965720d0243c639a04d231290138f7903faece6856e2893e4284527e18dae9e0f3308c47258acd7da769cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a475c3cf2642e1f87fdb8725796fcac646574bfd22dc75a66eeba6cb350d234144326ec07e778601cc114fef444c71365c3e74b3e0990114060a8c6c791b6d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 20:09:22.545145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 20:09:22.545464) ========= 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.XxcHnfZt6K/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.xN3LOS9Cex/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.005053 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 20:09:22.594414) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 20:09:22.597799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 20:09:22.598119) ========= ========= Total master_lag: 0.20 =========