builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0182 starttime: 1484330318.62 results: success (0) buildid: 20170113075917 builduid: 9ea2238896ba49b99d9eac59d95ccbf1 revision: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.617514) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.618031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.618367) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.644699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.645086) ========= 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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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-13 09:58:38-- 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% 171M=0s 2017-01-13 09:58:38 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133125 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.802802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.803176) ========= 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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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.026858 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:38.852399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 09:58:38.852783) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4749ac0674d3ba7884bc444d4132cb16b9c41a2b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4749ac0674d3ba7884bc444d4132cb16b9c41a2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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-13 09:58:38,954 truncating revision to first 12 chars 2017-01-13 09:58:38,954 Setting DEBUG logging. 2017-01-13 09:58:38,954 attempt 1/10 2017-01-13 09:58:38,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4749ac0674d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 09:58:40,053 unpacking tar archive at: mozilla-aurora-4749ac0674d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376054 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 09:58:40.252845) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:40.253258) ========= script_repo_revision: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:40.253713) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:40.254043) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:40.274034) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-13 09:58:40.274384) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:58:40 INFO - MultiFileLogger online at 20170113 09:58:40 in /builds/slave/test 09:58:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:58:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:40 INFO - {'append_to_log': False, 09:58:40 INFO - 'base_work_dir': '/builds/slave/test', 09:58:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:58:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:40 INFO - 'branch': 'Mozilla-Aurora', 09:58:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:40 INFO - 'config_files': ('talos/mac_config.py',), 09:58:40 INFO - 'default_actions': ('clobber', 09:58:40 INFO - 'read-buildbot-config', 09:58:40 INFO - 'download-and-extract', 09:58:40 INFO - 'populate-webroot', 09:58:40 INFO - 'create-virtualenv', 09:58:40 INFO - 'install', 09:58:40 INFO - 'run-tests'), 09:58:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:40 INFO - 'download_minidump_stackwalk': True, 09:58:40 INFO - 'download_symbols': 'ondemand', 09:58:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:40 INFO - 'installer_path': 'installer.exe', 09:58:40 INFO - 'log_level': 'info', 09:58:40 INFO - 'log_name': 'talos', 09:58:40 INFO - 'log_to_console': True, 09:58:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:58:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:58:40 INFO - 'opt_config_files': (), 09:58:40 INFO - 'pip_index': False, 09:58:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:40 INFO - 'cmd': ('bash', 09:58:40 INFO - '-c', 09:58:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:40 INFO - 'enabled': True, 09:58:40 INFO - 'halt_on_failure': False, 09:58:40 INFO - 'name': 'check_screen_resolution'},), 09:58:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:40 INFO - 'cmd': ('bash', 09:58:40 INFO - '-c', 09:58:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:40 INFO - 'enabled': True, 09:58:40 INFO - 'halt_on_failure': False, 09:58:40 INFO - 'name': 'check_screen_resolution'},), 09:58:40 INFO - 'run_cmd_checks_enabled': True, 09:58:40 INFO - 'sps_profile': False, 09:58:40 INFO - 'sps_profile_interval': 0, 09:58:40 INFO - 'suite': 'dromaeojs-e10s', 09:58:40 INFO - 'system_bits': '32', 09:58:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:40 INFO - 'title': 't-yosemite-r7-0182', 09:58:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:40 INFO - 'use_talos_json': True, 09:58:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:40 INFO - 'work_dir': 'build'} 09:58:40 INFO - [mozharness: 2017-01-13 17:58:40.479153Z] Running clobber step. 09:58:40 INFO - Running pre-action listener: _resource_record_pre_action 09:58:40 INFO - Running main action method: clobber 09:58:40 INFO - rmtree: /builds/slave/test/build 09:58:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:42 INFO - Running post-action listener: _resource_record_post_action 09:58:42 INFO - [mozharness: 2017-01-13 17:58:42.792780Z] Finished clobber step (success) 09:58:42 INFO - [mozharness: 2017-01-13 17:58:42.792908Z] Running read-buildbot-config step. 09:58:42 INFO - Running pre-action listener: _resource_record_pre_action 09:58:42 INFO - Running main action method: read_buildbot_config 09:58:42 INFO - Using buildbot properties: 09:58:42 INFO - { 09:58:42 INFO - "project": "", 09:58:42 INFO - "product": "firefox", 09:58:42 INFO - "script_repo_revision": "production", 09:58:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:58:42 INFO - "repository": "", 09:58:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:58:42 INFO - "buildid": "20170113075917", 09:58:42 INFO - "pgo_build": "False", 09:58:42 INFO - "basedir": "/builds/slave/test", 09:58:42 INFO - "buildnumber": 13, 09:58:42 INFO - "slavename": "t-yosemite-r7-0182", 09:58:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:58:42 INFO - "platform": "yosemite_r7", 09:58:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:58:42 INFO - "branch": "mozilla-aurora", 09:58:42 INFO - "repo_path": "releases/mozilla-aurora", 09:58:42 INFO - "revision": "4749ac0674d3ba7884bc444d4132cb16b9c41a2b", 09:58:42 INFO - "stage_platform": "macosx64", 09:58:42 INFO - "builduid": "9ea2238896ba49b99d9eac59d95ccbf1", 09:58:42 INFO - "slavebuilddir": "test" 09:58:42 INFO - } 09:58:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:58:42 INFO - Running post-action listener: _resource_record_post_action 09:58:42 INFO - [mozharness: 2017-01-13 17:58:42.796256Z] Finished read-buildbot-config step (success) 09:58:42 INFO - [mozharness: 2017-01-13 17:58:42.796392Z] Running download-and-extract step. 09:58:42 INFO - Running pre-action listener: _resource_record_pre_action 09:58:42 INFO - Running main action method: download_and_extract 09:58:42 INFO - mkdir: /builds/slave/test/build/tests 09:58: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')]} 09:58:42 INFO - trying https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:58:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:58:44 INFO - Downloaded 1199 bytes. 09:58:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:58:44 INFO - Using the following test package requirements: 09:58:44 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:58:44 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:58:44 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:58:44 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'jsshell-mac.zip'], 09:58:44 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:58:44 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:58:44 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:58:44 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:58:44 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:58:44 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:44 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:58:44 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:58:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:58:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:58:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:58:44 INFO - Http code: 200 09:58:44 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 09:58:44 INFO - x-amz-cf-id: _k6KTxmejiOMvSga-ax7u_QOco6HW80SrlwMsLrmm4_IqQKK1pt4JA== 09:58:44 INFO - x-amz-version-id: dpJxvyukvoeRttjJwKj49BesGfXKcgi9 09:58:45 INFO - Expected file size: 16275692 09:58:45 INFO - Obtained file size: 16275692 09:58:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:58:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:58:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:58:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:58:52 INFO - Http code: 200 09:58:52 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 09:58:52 INFO - x-amz-cf-id: OFxNYwo6u82G2GyQpK56NWQlO_0b0TuO06Zob8MKAcnV7cdLSxHlFw== 09:58:52 INFO - x-amz-version-id: dpJxvyukvoeRttjJwKj49BesGfXKcgi9 09:58:52 INFO - Expected file size: 16275692 09:58:52 INFO - Obtained file size: 16275692 09:58:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:58:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:58:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:58:59 INFO - Http code: 200 09:58:59 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:58:59 INFO - x-amz-cf-id: Fq3JxsMKa2g9hdFmWL6cGrX5OnvXtVJ0eq5SK_Jd2378_1ydYTlqxQ== 09:58:59 INFO - x-amz-version-id: 6TBJ9CNMd2u8L8cTbVcX6YlTQkreiw8p 09:59:00 INFO - Expected file size: 14409038 09:59:00 INFO - Obtained file size: 14409038 09:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:00 INFO - trying https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:59:03 INFO - Downloaded 109996773 bytes. 09:59:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:03 INFO - mkdir: /builds/slave/test/properties 09:59:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:03 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:03 INFO - Contents: 09:59:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:03 INFO - Running post-action listener: _resource_record_post_action 09:59:03 INFO - Running post-action listener: set_extra_try_arguments 09:59:03 INFO - [mozharness: 2017-01-13 17:59:03.910617Z] Finished download-and-extract step (success) 09:59:03 INFO - [mozharness: 2017-01-13 17:59:03.910731Z] Running populate-webroot step. 09:59:03 INFO - Running pre-action listener: _resource_record_pre_action 09:59:03 INFO - Running main action method: populate_webroot 09:59:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:03 INFO - '--tppagecycles', 09:59:03 INFO - '7'], 09:59:03 INFO - 'tests': ['tsvgm']}}, 09:59:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['damp', 'tps']}, 09:59:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'tests': ['damp', 'tps']}, 09:59:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['dromaeo_dom']}, 09:59:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['basic_compositor_video']}, 09:59:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['a11yr', 09:59:03 INFO - 'ts_paint', 09:59:03 INFO - 'tpaint', 09:59:03 INFO - 'sessionrestore', 09:59:03 INFO - 'sessionrestore_no_auto_restore', 09:59:03 INFO - 'tabpaint']}, 09:59:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['tsvgx', 09:59:03 INFO - 'tsvgr_opacity', 09:59:03 INFO - 'tart', 09:59:03 INFO - 'tscrollx', 09:59:03 INFO - 'cart']}, 09:59:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:03 INFO - 'tsvgr_opacity', 09:59:03 INFO - 'tart', 09:59:03 INFO - 'tscrollx', 09:59:03 INFO - 'cart']}, 09:59:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'talos_options': ['--disable-e10s'], 09:59:03 INFO - 'tests': ['tp5o']}, 09:59:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'tests': ['tp5o']}, 09:59:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'talos_options': ['--disable-e10s', 09:59:03 INFO - '--xperf_path', 09:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:03 INFO - 'tests': ['tp5n']}, 09:59:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:03 INFO - 'talos_options': ['--xperf_path', 09:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:03 INFO - 'tests': ['tp5n']}}, 09:59:03 INFO - 'talos.zip': {'path': '', 09:59:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:03 INFO - Running post-action listener: _resource_record_post_action 09:59:03 INFO - [mozharness: 2017-01-13 17:59:03.929280Z] Finished populate-webroot step (success) 09:59:03 INFO - [mozharness: 2017-01-13 17:59:03.929421Z] Running create-virtualenv step. 09:59:03 INFO - Running pre-action listener: _resource_record_pre_action 09:59:03 INFO - Running main action method: create_virtualenv 09:59:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:03 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 09:59:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:04 INFO - Using real prefix '/tools/python27' 09:59:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:07 INFO - Installing pip.................done. 09:59:07 INFO - Return code: 0 09:59:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:07 INFO - Reading from file tmpfile_stdout 09:59:07 INFO - Output received: 09:59:07 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) 09:59:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:07 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 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:07 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 09:59:07 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 09:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:07 INFO - 'HOME': '/Users/cltbld', 09:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:07 INFO - 'LOGNAME': 'cltbld', 09:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:07 INFO - 'MOZ_NO_REMOTE': '1', 09:59:07 INFO - 'NO_EM_RESTART': '1', 09:59:07 INFO - 'PAGER': '/bin/cat', 09:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:07 INFO - 'PWD': '/builds/slave/test', 09:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:07 INFO - 'SHELL': '/bin/bash', 09:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:07 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:07 INFO - 'USER': 'cltbld', 09:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:07 INFO - 'XPC_FLAGS': '0x0', 09:59:07 INFO - 'XPC_SERVICE_NAME': '0', 09:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:08 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 09:59:08 INFO - Cleaning up... 09:59:08 INFO - Return code: 0 09:59:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:08 INFO - 'HOME': '/Users/cltbld', 09:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:08 INFO - 'LOGNAME': 'cltbld', 09:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:08 INFO - 'MOZ_NO_REMOTE': '1', 09:59:08 INFO - 'NO_EM_RESTART': '1', 09:59:08 INFO - 'PAGER': '/bin/cat', 09:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:08 INFO - 'PWD': '/builds/slave/test', 09:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:08 INFO - 'SHELL': '/bin/bash', 09:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:08 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:08 INFO - 'USER': 'cltbld', 09:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:08 INFO - 'XPC_FLAGS': '0x0', 09:59:08 INFO - 'XPC_SERVICE_NAME': '0', 09:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:08 INFO - Downloading/unpacking psutil>=3.1.1 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:11 INFO - Installing collected packages: psutil 09:59:11 INFO - Running setup.py install for psutil 09:59:12 INFO - building 'psutil._psutil_osx' extension 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:59:12 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 09:59:12 INFO - building 'psutil._psutil_posix' extension 09:59:12 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 09:59:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:59:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:59:12 INFO - ^ 09:59:12 INFO - 1 warning generated. 09:59:12 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 09:59:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:13 INFO - Successfully installed psutil 09:59:13 INFO - Cleaning up... 09:59:13 INFO - Return code: 0 09:59:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:13 INFO - 'HOME': '/Users/cltbld', 09:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:13 INFO - 'LOGNAME': 'cltbld', 09:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:13 INFO - 'MOZ_NO_REMOTE': '1', 09:59:13 INFO - 'NO_EM_RESTART': '1', 09:59:13 INFO - 'PAGER': '/bin/cat', 09:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:13 INFO - 'PWD': '/builds/slave/test', 09:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:13 INFO - 'SHELL': '/bin/bash', 09:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:13 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:13 INFO - 'USER': 'cltbld', 09:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:13 INFO - 'XPC_FLAGS': '0x0', 09:59:13 INFO - 'XPC_SERVICE_NAME': '0', 09:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:16 INFO - Installing collected packages: mozsystemmonitor 09:59:16 INFO - Running setup.py install for mozsystemmonitor 09:59:16 INFO - Successfully installed mozsystemmonitor 09:59:16 INFO - Cleaning up... 09:59:16 INFO - Return code: 0 09:59:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:59:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:16 INFO - 'HOME': '/Users/cltbld', 09:59:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:16 INFO - 'LOGNAME': 'cltbld', 09:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:16 INFO - 'MOZ_NO_REMOTE': '1', 09:59:16 INFO - 'NO_EM_RESTART': '1', 09:59:16 INFO - 'PAGER': '/bin/cat', 09:59:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:16 INFO - 'PWD': '/builds/slave/test', 09:59:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:16 INFO - 'SHELL': '/bin/bash', 09:59:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:16 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:16 INFO - 'USER': 'cltbld', 09:59:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:16 INFO - 'XPC_FLAGS': '0x0', 09:59:16 INFO - 'XPC_SERVICE_NAME': '0', 09:59:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:19 INFO - warning: no files found matching '*.txt' 09:59:19 INFO - no previously-included directories found matching 'build' 09:59:19 INFO - no previously-included directories found matching 'dist' 09:59:19 INFO - no previously-included directories found matching '.git*' 09:59:19 INFO - Installing collected packages: jsonschema, functools32 09:59:19 INFO - Running setup.py install for functools32 09:59:19 INFO - warning: no files found matching '*.txt' 09:59:19 INFO - no previously-included directories found matching 'build' 09:59:19 INFO - no previously-included directories found matching 'dist' 09:59:19 INFO - no previously-included directories found matching '.git*' 09:59:19 INFO - Successfully installed jsonschema functools32 09:59:19 INFO - Cleaning up... 09:59:20 INFO - Return code: 0 09:59:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:20 INFO - 'HOME': '/Users/cltbld', 09:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:20 INFO - 'LOGNAME': 'cltbld', 09:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:20 INFO - 'MOZ_NO_REMOTE': '1', 09:59:20 INFO - 'NO_EM_RESTART': '1', 09:59:20 INFO - 'PAGER': '/bin/cat', 09:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:20 INFO - 'PWD': '/builds/slave/test', 09:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:20 INFO - 'SHELL': '/bin/bash', 09:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:20 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:20 INFO - 'USER': 'cltbld', 09:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:20 INFO - 'XPC_FLAGS': '0x0', 09:59:20 INFO - 'XPC_SERVICE_NAME': '0', 09:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:59:20 INFO - Cleaning up... 09:59:20 INFO - Return code: 0 09:59:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:20 INFO - 'HOME': '/Users/cltbld', 09:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:20 INFO - 'LOGNAME': 'cltbld', 09:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:20 INFO - 'MOZ_NO_REMOTE': '1', 09:59:20 INFO - 'NO_EM_RESTART': '1', 09:59:20 INFO - 'PAGER': '/bin/cat', 09:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:20 INFO - 'PWD': '/builds/slave/test', 09:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:20 INFO - 'SHELL': '/bin/bash', 09:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:20 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:20 INFO - 'USER': 'cltbld', 09:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:20 INFO - 'XPC_FLAGS': '0x0', 09:59:20 INFO - 'XPC_SERVICE_NAME': '0', 09:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:24 INFO - Downloading docopt-0.6.1.tar.gz 09:59:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:24 INFO - Running setup.py install for blobuploader 09:59:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:24 INFO - Running setup.py install for requests 09:59:25 INFO - Running setup.py install for docopt 09:59:25 INFO - Successfully installed blobuploader requests docopt 09:59:25 INFO - Cleaning up... 09:59:25 INFO - Return code: 0 09:59:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:25 INFO - 'HOME': '/Users/cltbld', 09:59:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:25 INFO - 'LOGNAME': 'cltbld', 09:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:25 INFO - 'MOZ_NO_REMOTE': '1', 09:59:25 INFO - 'NO_EM_RESTART': '1', 09:59:25 INFO - 'PAGER': '/bin/cat', 09:59:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:25 INFO - 'PWD': '/builds/slave/test', 09:59:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:25 INFO - 'SHELL': '/bin/bash', 09:59:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:25 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:25 INFO - 'USER': 'cltbld', 09:59:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:25 INFO - 'XPC_FLAGS': '0x0', 09:59:25 INFO - 'XPC_SERVICE_NAME': '0', 09:59:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:25 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-7NLgqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:25 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-JLokA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:25 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-X3p14d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-i7sy5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-0X3tSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-sHfsaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-9l3hfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-4FSvmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-0U_qC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-EkatgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-ovumHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:26 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-vgSVyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:27 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-H1AiZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:27 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-veyloj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:27 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-7HzOQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:27 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-6FMh9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:27 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-YxzafP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:27 INFO - Running setup.py install for manifestparser 09:59:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Running setup.py install for mozcrash 09:59:27 INFO - Running setup.py install for mozdebug 09:59:27 INFO - Running setup.py install for mozdevice 09:59:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozfile 09:59:28 INFO - Running setup.py install for mozhttpd 09:59:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozinfo 09:59:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozInstall 09:59:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozleak 09:59:28 INFO - Running setup.py install for mozlog 09:59:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Running setup.py install for moznetwork 09:59:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Running setup.py install for mozprocess 09:59:29 INFO - Running setup.py install for mozprofile 09:59:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Running setup.py install for mozrunner 09:59:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Running setup.py install for mozscreenshot 09:59:29 INFO - Running setup.py install for moztest 09:59:29 INFO - Running setup.py install for mozversion 09:59:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:30 INFO - Cleaning up... 09:59:30 INFO - Return code: 0 09:59:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:30 INFO - 'HOME': '/Users/cltbld', 09:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:30 INFO - 'LOGNAME': 'cltbld', 09:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:30 INFO - 'MOZ_NO_REMOTE': '1', 09:59:30 INFO - 'NO_EM_RESTART': '1', 09:59:30 INFO - 'PAGER': '/bin/cat', 09:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:30 INFO - 'PWD': '/builds/slave/test', 09:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:30 INFO - 'SHELL': '/bin/bash', 09:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:30 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:30 INFO - 'USER': 'cltbld', 09:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:30 INFO - 'XPC_FLAGS': '0x0', 09:59:30 INFO - 'XPC_SERVICE_NAME': '0', 09:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-nkz0jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-40fG9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-RX3VKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-T6gGA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:30 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)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-hcf0VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:30 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)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:30 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-b5mk1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-OFalH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-fuOhne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-uKf7rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-03FHq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-Czztya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-iyMZTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-DgKWoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-IhlIwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:31 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-mgtGjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:32 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-7djh1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:32 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)) 09:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:32 INFO - Running setup.py (path:/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/pip-Ap9d1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:59:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:34 INFO - Downloading blessings-1.6.tar.gz 09:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:35 INFO - Installing collected packages: blessings 09:59:35 INFO - Running setup.py install for blessings 09:59:35 INFO - Successfully installed blessings 09:59:35 INFO - Cleaning up... 09:59:35 INFO - Return code: 0 09:59:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:35 INFO - Reading from file tmpfile_stdout 09:59:35 INFO - Current package versions: 09:59:35 INFO - blessings == 1.6 09:59:35 INFO - blobuploader == 1.2.4 09:59:35 INFO - docopt == 0.6.1 09:59:35 INFO - functools32 == 3.2.3-2 09:59:35 INFO - jsonschema == 2.5.1 09:59:35 INFO - manifestparser == 1.1 09:59:35 INFO - mozInstall == 1.12 09:59:35 INFO - mozcrash == 1.0 09:59:35 INFO - mozdebug == 0.1 09:59:35 INFO - mozdevice == 0.48 09:59:35 INFO - mozfile == 1.2 09:59:35 INFO - mozhttpd == 0.7 09:59:35 INFO - mozinfo == 0.9 09:59:35 INFO - mozleak == 0.1 09:59:35 INFO - mozlog == 3.4 09:59:35 INFO - moznetwork == 0.27 09:59:35 INFO - mozprocess == 0.23 09:59:35 INFO - mozprofile == 0.28 09:59:35 INFO - mozrunner == 6.13 09:59:35 INFO - mozscreenshot == 0.1 09:59:35 INFO - mozsystemmonitor == 0.3 09:59:35 INFO - moztest == 0.8 09:59:35 INFO - mozversion == 1.4 09:59:35 INFO - psutil == 3.1.1 09:59:35 INFO - requests == 1.2.3 09:59:35 INFO - wsgiref == 0.1.2 09:59:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:36 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 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:36 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 09:59:36 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 09:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:36 INFO - 'HOME': '/Users/cltbld', 09:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:36 INFO - 'LOGNAME': 'cltbld', 09:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:36 INFO - 'MOZ_NO_REMOTE': '1', 09:59:36 INFO - 'NO_EM_RESTART': '1', 09:59:36 INFO - 'PAGER': '/bin/cat', 09:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:36 INFO - 'PWD': '/builds/slave/test', 09:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:36 INFO - 'SHELL': '/bin/bash', 09:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:36 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:36 INFO - 'USER': 'cltbld', 09:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:36 INFO - 'XPC_FLAGS': '0x0', 09:59:36 INFO - 'XPC_SERVICE_NAME': '0', 09:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 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)) 09:59:36 INFO - Cleaning up... 09:59:36 INFO - Return code: 0 09:59:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59:36 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')]} 09:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:36 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')]} 09:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:36 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 0x10fddc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd52570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa973d7c3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, '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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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 09:59:36 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 09:59:36 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 09:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 09:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:36 INFO - 'HOME': '/Users/cltbld', 09:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:36 INFO - 'LOGNAME': 'cltbld', 09:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:36 INFO - 'MOZ_NO_REMOTE': '1', 09:59:36 INFO - 'NO_EM_RESTART': '1', 09:59:36 INFO - 'PAGER': '/bin/cat', 09:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:36 INFO - 'PWD': '/builds/slave/test', 09:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:36 INFO - 'SHELL': '/bin/bash', 09:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 09:59:36 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 09:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:36 INFO - 'USER': 'cltbld', 09:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:36 INFO - 'XPC_FLAGS': '0x0', 09:59:36 INFO - 'XPC_SERVICE_NAME': '0', 09:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:59:37 INFO - Cleaning up... 09:59:37 INFO - Return code: 0 09:59:37 INFO - Running post-action listener: _resource_record_post_action 09:59:37 INFO - Running post-action listener: _start_resource_monitoring 09:59:37 INFO - Starting resource monitoring. 09:59:37 INFO - [mozharness: 2017-01-13 17:59:37.247913Z] Finished create-virtualenv step (success) 09:59:37 INFO - [mozharness: 2017-01-13 17:59:37.248870Z] Running install step. 09:59:37 INFO - Running pre-action listener: _resource_record_pre_action 09:59:37 INFO - Running main action method: install 09:59:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:37 INFO - Reading from file tmpfile_stdout 09:59:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:37 INFO - Reading from file tmpfile_stdout 09:59:37 INFO - Output received: 09:59:37 INFO - Usage: mozinstall [options] installer 09:59:37 INFO - Options: 09:59:37 INFO - -h, --help show this help message and exit 09:59:37 INFO - -d DEST, --destination=DEST 09:59:37 INFO - Directory to install application into. [default: 09:59:37 INFO - "/builds/slave/test"] 09:59:37 INFO - --app=APP Application being installed. [default: firefox] 09:59:37 INFO - mkdir: /builds/slave/test/build/application 09:59:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:00:08 INFO - Reading from file tmpfile_stdout 10:00:08 INFO - Output received: 10:00:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:00:08 INFO - Running post-action listener: _resource_record_post_action 10:00:08 INFO - [mozharness: 2017-01-13 18:00:08.670002Z] Finished install step (success) 10:00:08 INFO - [mozharness: 2017-01-13 18:00:08.670134Z] Running run-tests step. 10:00:08 INFO - Running pre-action listener: _resource_record_pre_action 10:00:08 INFO - Running main action method: run_tests 10:00:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:08 INFO - 2017-01-13 10:00:08.684 screenresolution[1622:7323] starting screenresolution argv=screenresolution get 10:00:08 INFO - 2017-01-13 10:00:08.687 screenresolution[1622:7323] Display 0: 1600x1200x32@60 10:00:08 INFO - 2017-01-13 10:00:08.693 screenresolution[1623:7326] starting screenresolution argv=screenresolution list 10:00:08 INFO - Available Modes on Display 0 10:00:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:08 INFO - Intel Iris: 10:00:08 INFO - Chipset Model: Intel Iris 10:00:08 INFO - Type: GPU 10:00:08 INFO - Bus: Built-In 10:00:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:08 INFO - Vendor: Intel (0x8086) 10:00:08 INFO - Device ID: 0x0a2e 10:00:08 INFO - Revision ID: 0x0009 10:00:08 INFO - Displays: 10:00:08 INFO - Display: 10:00:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:08 INFO - Display Serial Number: 1600x1200 60 10:00:08 INFO - Main Display: Yes 10:00:08 INFO - Mirror: Off 10:00:08 INFO - Online: Yes 10:00:08 INFO - Rotation: Supported 10:00:08 INFO - Return code: 0 10:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:08 INFO - Python 2.7.3 10:00:08 INFO - Return code: 0 10:00:08 INFO - grabbing minidump binary from tooltool 10:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:08 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 0x10fbf0d30>, '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 0x10fa56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:08 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 10:00:08 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 10:00:08 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 10:00:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:09 INFO - Return code: 0 10:00:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:00:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:00:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 10:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0182 --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 10:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMEu3XQH0C/Render', 10:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:09 INFO - 'HOME': '/Users/cltbld', 10:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:09 INFO - 'LOGNAME': 'cltbld', 10:00:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:09 INFO - 'MOZ_NO_REMOTE': '1', 10:00:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:09 INFO - 'NO_EM_RESTART': '1', 10:00:09 INFO - 'PAGER': '/bin/cat', 10:00:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:09 INFO - 'PWD': '/builds/slave/test', 10:00:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:09 INFO - 'SHELL': '/bin/bash', 10:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W9BzQaeR0e/Listeners', 10:00:09 INFO - 'TMPDIR': '/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/', 10:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:09 INFO - 'USER': 'cltbld', 10:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:09 INFO - 'XPC_FLAGS': '0x0', 10:00:09 INFO - 'XPC_SERVICE_NAME': '0', 10:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 10:00:09 INFO - mozversion application_buildid: 20170113075917 10:00:09 INFO - mozversion application_changeset: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b 10:00:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:00:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:09 INFO - mozversion application_name: Firefox 10:00:09 INFO - mozversion application_remotingname: firefox-dev 10:00:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:09 INFO - mozversion application_vendor: Mozilla 10:00:09 INFO - mozversion application_version: 52.0a2 10:00:09 INFO - mozversion platform_buildid: 20170113075917 10:00:09 INFO - mozversion platform_changeset: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b 10:00:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:09 INFO - mozversion platform_version: 52.0a2 10:00:09 INFO - using testdate: 1484330409 10:00:09 INFO - actual date: 1484330409 10:00:09 INFO - starting webserver on 'localhost:49235' 10:00:09 INFO - SUITE-START | Running 2 tests 10:00:09 INFO - TEST-START | dromaeo_css 10:00:09 INFO - operating with platform_type : mac_ 10:00:09 INFO - Initialising browser for dromaeo_css test... 10:00:09 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/tmpN7Tj5z/profile http://localhost:49235/getInfo.html) 10:00:12 INFO - PROCESS | 1633 | 2017-01-13 10:00:12.574 plugin-container[1635:7508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 10:00:12 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:12 INFO - PROCESS | 1633 | 2017-01-13 10:00:12.577 plugin-container[1635:7508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 10:00:12 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:12 INFO - PROCESS | 1633 | 2017-01-13 10:00:12.577 plugin-container[1635:7508] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:12 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 10:00:12 INFO - PROCESS | 1633 | colorDepth:24 10:00:12 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 10:00:12 INFO - PROCESS | 1633 | __metrics 10:00:12 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:00:13 INFO - TEST-INFO | 1633: exit 0 10:00:13 INFO - Browser initialized. 10:00:13 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:13 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/tmpN7Tj5z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:16 INFO - PROCESS | 1636 | 2017-01-13 10:00:16.190 plugin-container[1637:7640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 10:00:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:16 INFO - PROCESS | 1636 | 2017-01-13 10:00:16.191 plugin-container[1637:7640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 10:00:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:16 INFO - PROCESS | 1636 | 2017-01-13 10:00:16.191 plugin-container[1637:7640] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:25 INFO - PROCESS | 1636 | RSS: Main: 229101568 10:00:25 INFO - PROCESS | 1636 | 10:03:27 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 10:03:29 INFO - PROCESS | 1636 | RSS: Main: 236093440 10:03:29 INFO - PROCESS | 1636 | 10:06:31 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 10:06:32 INFO - PROCESS | 1636 | RSS: Main: 243605504 10:06:32 INFO - PROCESS | 1636 | 10:09:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 10:09:34 INFO - PROCESS | 1636 | RSS: Main: 246738944 10:09:34 INFO - PROCESS | 1636 | 10:12:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 10:12:37 INFO - PROCESS | 1636 | RSS: Main: 246038528 10:12:37 INFO - PROCESS | 1636 | 10:15:40 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 10:15:40 INFO - PROCESS | 1636 | RSS: Main: 249458688 10:15:40 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 10:18:43 INFO - PROCESS | 1636 | RSS: Main: 250904576 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | __start_tp_report 10:18:43 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 10:18:43 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 10:18:43 INFO - PROCESS | 1636 | |i|pagename|runs| 10:18:43 INFO - PROCESS | 1636 | |0;dojo.html;3248.2529018325768;3288.572485576875;3319.0706602151404;3319.352726218385;3325.418051840928;5406.486383793543;5410.1073322901775;5420.91868621971;5460.262864513289;5522.585806064551;5499.312585926759;5531.917021244684;5540.168974653804;5552.278203833506;5553.666779993203;4996.175631020896;5004.524570165829;5115.976804639087;5131;5176.275321454999;5045.823396181135;5071.949280507185;5113.693178409308;5116.872078198037;5143.7685304161405;4870.805167793319;4914.803407863681;5030.2957585938;5067.39191297043;5082.390113186404;3982.8406863725418;4290.184864875664;4970.801167953277;4978.054169707781;5001.324821149135;4138.896527586804;4901.093297739894;4923.753812309406;4930.4330002049865;5013.473585273567;5109.463506331819;5403.621746477748;5481.643693159932;5484.890302193934;5493.533049690781;5916.9941110011405;5982.970085149546;5997.160397544348;6018.21763170785;6058.969705151446;6068.271807383098;6079.270487541479;6097.024476083805;6121;6127.44852963231;5448.945510544923;5490.615656903979;5511.779528818883;5614.85962850932;5630.436956304377;6116.3577824328695;6154.261488621304;6162.22972128484;6167.814965551041;6179.16581261533;5822.854428639318;5826.79606213789;5846;5847;5857.26784151981;5793.565482588739;5883.470487656132;5952.196453478811;5977.910331345033;5981.850453738604;4833.468318484964;5834.504067154339;5864.091065884794;5871.500922421556;5993.550483713739;5920.496757775637;5927.436893495111;5935.2877654681715;5966.015607424813;5974.910376344358;4770.356001939694;4787.640926930494;4793.352897358882;4835.008823191238;4895.755212239374;4254.87235382939;4281.935770963438;4301.677374196947;4308.202982448257;4397.34039894012;4550.590446859798;5476.67139971603;5644.943550564524;5664.235328230636;5674.943250567524;6065.484433823086;6085.330613632497;6091.02543593023;6091.08633704939;6095.664738439428;5461.42655021225;5567.387587365387;5575.581831362664;5587.022271102541;5600.467955544217;5216.60875434344;5240.4759524047295;5243;5254.054270231395;5263.6578622389425;6232.56372053961;6275.027370757449;6276;6283.403076707706;6303.432691057827;4940.5800506955875;5749.683767392834;5779.884402312015;5973.522118230444;6030.3668114847305;6256.624602523863;6260.40526150015;6292.968535157295;6295.213098362705;6295.685215739103;5910.970445147746;5915.792947246826;5916.408359164049;5927.940720592911;5941.643501389931;5794.333651630117;5821.679807610452;5888.293404791452;5933.792317268875;6010.0083486224285;5570.582206334622;5682.573806964577;5760.740766665594;5761.654300741969;5788.82633520995;5733.967885780433;5751.4536119068625;5764.337101233412;5781.739821707949;5802.1586869902785;5508.697021663847;5515.641483303572;5521.337439507285;5707.914381284367;5716.685582293014;5825.679587622722;5838.386969368155;5848.122781582627;5861.0915308127305;5886.793962211302;4764.190087685171;5678;5697.971510142423;5704.3725190228215;5724.313082430135 10:18:43 INFO - PROCESS | 1636 | |1;ext.html;481.930120132581;482.6114977443158;483.5647916874812;519.7474087449248;526.1292560811853;35314.64685353152;38134.80932595336;39555.604443955555;39625.20749585007;39720.808375748704;38794.4180837287;41670.791646041806;41682;41719;41786;9362.110599493037;9449.818772653418;9470.052994700543;9515;9525.380850244712;8044.718434854782;9520.809583808319;9556.283278754086;9556.713298601018;9572.56923438447;8376.53929033903;9014.68448604299;9484.19384352331;9537.380070295443;9540.80918381632;8698.695545655903;9570.521473926345;9571.28215383849;9577.425354478684;9577.521123943845;9415.529223538795;9420.905790942072;9484.525773711286;9499;9523.714288571355;38876.80561597202;38997.02507437309;39223.215535689415;39240.01899952495;39312.803435982925;3442.569678790151;3529.205928666055;3812;3940.192260586573;3977.6022397759994;28753.13740587765;29243.415131697246;29352.559711604343;29667.258318541994;30224.24439389011;3173.690963947939;3193.457112290916;3212.8714851405916;3215.340855124694;3303.438415469376;522558;525829;529312;529395;532710;50149;62066.44833879189;62099.6895015522;62393;62940.685296573225;54826.72586637041;64126.67936660287;65574.67212664001;65586.67206663937;65679.34320656827;16136.67726645484;16372.754408683879;16391.83608163927;16402.425915092914;16414.015159090493;12264;12399.504019839285;12499;12506.062045346453;12512.374381280899;17496.985227615278;18636.813631863508;18744.81255187458;18897.33859314945;19436.70844937327;7099.3965512930945;7136.929460580955;7510.549367037996;7690.807729806687;7733.613319334123;703.8281261699213;935.2004036548789;956.9665061722807;1020.8162530744389;1051.7423231308226;29190.416191676046;29663;30028;30321.69678303233;30368.84815575908;908.9909100908907;997.2769741937037;1064.3188359449957;1069.2194697870443;1090.3621381491757;15116.395344186329;15212.923935380473;15429.922850385676;15491;15699.607509812346;650188;655758;656613;657896;658670;56294.71852640792;56635.716821415626;56639.43360566341;56710.7164464175;56911.29266122023;1323.602919124259;1346.9964876167123;1366.0983750724474;1366.4124426496503;1368.9726205475836;28786.992455263964;29313.85343073356;29698.851505742332;30240.09279721612;30344;50201;50531;50623;50656.49343506665;50837;576765;579958;584687;587486;587605;25780.742192578586;26422.339441513348;26458.86770566135;26658.866705666347;26707.33231669146;37239.88280351594;45628.31557526573;45860;45861.31208031815;46492.7675361621;31809.840950796024;38375.80812095922;38631;38741.61258387493;40012;27524.724752753016;38247.42628860625;38510.42234366543;39186.216275674895;39537.406938896514;32030.839845801554;32608.83695581507;37182.44226336553;37274.81362593278;38343.04142396463;27722;30498;33947.83026084854;34201;34262.31475370429;24641.521508709535;25229.369265768502;25695.743042569335;25707.614385784604;26079.739202607732 10:18:43 INFO - PROCESS | 1636 | |2;jquery.html;329.88783813503403;341.16755117513253;374.74330083892534;382.41108692613375;398.3308042488618;16213.351465941378;18207.453776386712;18815.905920470395;19631.705524417146;19653.312134075273;19440.90279548604;19513;20260;20272.695909561367;20366.592668146666;5639.323281206251;5782.53739700825;5784.710764461781;5881.647101173922;5891.911621325684;5462.644928079662;5740.79907203248;5796;5862.9413705863035;5868.914250863604;5798.65208087514;5818.650880947157;5836.182934389189;5840.474357307863;5844.2402487676545;5722.771089156428;6420.743170273184;6454.064160696673;6466.838329041788;6473.446372870524;5449.291592093003;5639.041362968305;5698.458646428583;5702.372738998707;5710.200571919934;12869.034822388357;12923.67690807728;12949;13142.802857957138;13151.802722959163;2029.1984666056971;2138.5081431270737;2212.371868445007;2298.931032069024;2350.5916421189345;9685.709428717078;9995.550200240934;10228.335158214692;12273.509059637692;14515;1737.6698427298772;1760.9647807043787;1830.331928845975;1862.9254829806791;1876.502726777405;119165.80834191634;128847.7115228845;129853.35073324667;132311.33844330814;136003.999999999;11491.310521368745;11619.244749091282;11724.88275117238;11743.530258789553;11750;14408.711825763427;14527.201003944885;14531.982761206615;14536.927315363568;14575.052621579563;7957.641906114239;8142.85999960006;8156.429049966446;8175.5503447309775;8240.958795205986;6469.708863101077;7922.841543169219;7924.920750792534;7973.681052757825;8001.239882211181;4010.7994600270454;4351.6518678505645;4503.707259028152;4514.864554063383;4518.9548104518535;1864.7016477363827;1903.60085337277;1905.7998910114238;1909.6467153576632;1910.235905637756;835.3985130705883;862.2369203255164;862.8921384826897;914.9843673522385;959.4195511715451;20023.59952800936;20928.79071209299;20981.68527472089;21048.15807367688;21357.932103394767;882.188386684252;896.8385690575759;915.3522764542787;931.7065124485765;955.861400096982;10345.27583069193;11173;11186.384748838893;11250.774984500264;11258.831117533244;451002;451389;452796;545415;625199;132172;132752;139865.30067349802;141224;141987.29006355107;933.5192375926404;953.3517208298424;988.9950550247347;992.6277645882794;992.6846927820523;18625.789323693916;19174.904125479283;19241.807581924;19374.321898733477;19424.708629370853;37362;43965.78017109894;51858.74070629623;53350.932981340935;54801.45198547964;63225.051624224754;76219.23780762122;77401.61299193469;79511.80732288904;80221.59889200516;7872.567008814341;7940.325072368682;8150.551719655476;8262.339012878834;8278.634025385985;10271;11885.881141188478;12244.142909996572;12608.693493040126;12847.164934279243;8629.654813807378;9371.859422108537;10883.292585981886;11093.944530277297;11263.831042534532;10638.042576167873;10662.46687665598;10696.197785165989;11043.39261340634;11056.723581910517;10834.079103275995;10964.890351096386;11020.944895275472;11369.602063927723;11383.260088094248;7086.220515743161;7603.0876294844975;7911.367090632851;8168.7957801055445;8171.978502687164;9340.71977840666;9501.239900807781;10752.623658171926;10756.623518176826;10944.343339399662 10:18:43 INFO - PROCESS | 1636 | |3;mootools.html;1208.9335086570238;1443.7978682984385;1556.8287488376277;1728.3086765293874;1733.4626265857585;7450.552966821994;7701;7960.880586791195;8457.53483558404;8467.661293548259;7913.643886025128;8206.425550211488;8224.547649879254;8318.459300145501;8341.54121523316;2301.8158547316216;2317.455397981473;2333.9183128590503;2336.4976530046038;2347.3099368454687;2325.395397196724;2342.2856028911174;2360.433495960974;2368.1948137633203;2368.9289321320302;2307.792298693117;2312.5490529346785;2340;2340.707411573553;2344.9648255276184;2297.1385730351117;2301.4591570980806;2318.5710643531;2325.813934885205;2327.743948165692;2321.0831721469926;2342.61346877765;2347.9647805282934;2349.119080344871;2353.51752890657;8238;8341.541215233221;8389.119142490074;8397.37019723517;8416.789580260482;4950.752462376867;5087.3386459759995;5096.872578185538;5099.08216521026;5102.1071312520535;3240.2709390386976;3385.8814941477176;3489.040513858684;3549;3554.928901421983;1020.8825985011745;1042.8435734639866;1076.7577295108617;1081.864766904137;1099.2964502718266;10774.353538787698;10776.78446431067;10849.891501084967;10867.565297388095;10890.128789696806;4510.345999830072;4671.252599584051;4744.146053710297;4789.090072886141;4793.616510679138;9447.6693315735;9448.196903263162;9456.290778191664;9460.195883349992;9474.715758527254;5153.793848246029;5192.81825136126;5210.374755029421;5213.765380557884;5217.113090774577;4241.597048280409;4267.4025636410925;4699.929501057487;4756.9286460703115;4778.283257511332;3764.887053388403;3811.599782022903;3866.980665096713;3907.9609203908167;3911.2959667260166;1064.462446464546;1254.7804134276464;1263.5577547858177;1269.6825793551611;1272.7963525835821;887.4985633117398;893.2942975049768;900.6127365232882;901.2699713232303;903.9548022598843;4871.8294859679745;5528.9723551381985;5653.462921022497;5701.71491425427;5802.4197580241635;686.7081490366575;817.887672765039;825.2778818533783;936.6487567162314;974.3812678948776;3367.7810942288675;3685.9863537527394;3783.181529760346;3807.8667246646787;3899.883003509899;8951.507667078244;10020;10126.696199114038;10170.898291016994;10258.43578603169;8163.3061189798345;9155.679551215675;10054.245931555162;10110.140638045701;10124.392536447836;7812.960935195401;7932.365410767125;7966.6415011324625;7982.241687039722;7984.08183058944;5553.333599968029;5564.4713752193475;5585.888282234414;5605.271314729059;5628.493435590816;9255.537223138681;9264.39781414206;9364.344495885223;9397.906020939976;9425.811483770423;9116.1339672731;9549.952250238983;9581.712548623553;9584.472853992818;9616.08647178517;5713.22871412354;6242.687865606792;6441.259255185713;6627.105340778933;6850.006749021264;7468.290512401313;8064.395170362129;8073.5963201838495;8084.272415482635;8087.595620219083;7057.117860267466;7189.496735228484;7191.352778249982;7217.494775365674;7306.232845551141;7022.789316320519;7050.421707929553;7134.85730285381;7161.892571611535;7275.017872587133;6631.33686631333;6805.9659701701175;6807.625580592917;6809.114815074008;6812.318768123148;4817.470078291455;4858.684185528071;4867.245576935581;4886.511348865085;4899.779509921991;4150.771707556113;4682.719036857799;4761.285807128958;4848.248521479177;4905.264210368473 10:18:43 INFO - PROCESS | 1636 | |4;prototype.html;304.0695471856119;305.463910836482;313.2153954344367;315.13652591898204;317.28768913788537;4207.284761590533;4429.335599660053;5185.066687996159;5454.400015998237;5574.606348412902;4203.036859014395;4437.138045960353;5018.121828679985;5120.129577971745;5383.892322153554;3601.7658852174654;3843.2889915365677;4113.568075352075;4122.134351786129;4139.296319625671;3421.631347461023;3797.5632802227683;3954.070793363557;4146.917061658765;4150.750954942698;3665.157013886808;3988.441618173458;3988.660963818079;4031.4759081319535;4032.495938007749;3158.4314823331792;3417;3494.899106781356;4009.3985902114623;4095.160492099142;2614.8038897082606;3483.9651603483894;3501.91245218869;3503.3693935091674;3521.612622611536;4137.462129923121;4258.680598955059;4540.523245059254;4644.396228490308;5203.973980130075;4159.854405095837;4375.7374557526655;4454.866354009385;4456.997175635456;4603.792829322655;4628.421447319085;5510.889782204374;5581.358143813432;5619.915701264485;5656.6888821115235;3343.8829640962695;3643.5521908876503;3715.349813782604;3835.2329534093014;3837.9466985163085;4843.733594652293;5130.563902068291;5456.63584103974;5785.305763308388;5906.822795316148;3058.3883223354974;3287.309664970383;3680.760750551259;3833.971162781427;3850.9807450962567;3467.6358982306497;3918.2359439908946;3923.214517692413;3930.469386632768;3949.6840252779707;4229.555896630871;4483.170613436525;4558.9514411685495;4588.655850811202;4616.6537509686905;4933.235348520985;5705.857353566194;5712.714364281769;5715.742791574306;5720.342160651495;1027.198784947742;1233.993830030844;1238.6965193527644;1276.6808297925518;1286.9935650321875;858.5833374931414;871.3813192633174;878.4597472554333;882.9337799665051;883.3419102768479;5094.624451398102;5141.820036298712;5154.845354639367;5178.498235511659;5181.53366197044;4349.608535231781;5499.50504454601;5516.393196748354;5547.972260138674;5605.523530499871;4502.3246513023305;4726.527347265314;4803.711777293373;5007.173816320266;5130.948690513122;2668.506326329635;2875.899343522967;2886.567014947733;2908.8254704717783;2917.6207093077765;4672.462666793294;4673.111879032375;5318.994767956093;5761.88476230473;5764.971175144181;4664.976675116603;5665.263515742927;5676.290463692038;5687.260656114761;5730.656160630376;4928.180659988538;5686.322534852228;5720.427957204246;5747.482726554546;5749.579000494699;5289.391719952101;5515.255440515479;5562.831805320766;5571.60998730085;5622.240997465454;4534.931976020296;4900.093482705711;5434.972825135849;5516.531094856982;5530.866172434722;5433.044104122434;5685.459881311269;5725.770969161354;5735.684537350486;5744.626599271034;3158.2313904213715;3198.5362122491642;3634.1459756956565;3700.243318727565;3733.9066523337133;4810.42274927011;4949.455559888337;5281.943611277837;5303.628745987899;5514.393416724238;4131.368109596632;4753.363676787191;4921.4832442593015;5072.195853110167;5303.549198318186;4721.952780472151;5159.071367153948;5280.287161233185;5323.653962492425;5333.386660534088;4405.9559404405545;5252.317198764136;5264;5268.917683155397;5456.399796022355;4714.434267887876;4957.504249575158;4969.801207951641;5711.771529138788;5733.971330143323;4597.42532183477;4708.552687494683;5138.820141295037;5316.388615309289;5361.758720857649 10:18:43 INFO - PROCESS | 1636 | |5;yui.html;698.9231184569699;712.1169749510602;748.072390236107;782.2920257167266;798.5607915646397;12212.511499540018;13739.24434156121;14269.35787889545;14479.855201447983;14520.709585808278;13045.543405980792;14185.290735463212;14728.705425891476;14776.187309697962;15170;651.8631087471613;673.9022841687952;799.5842162075703;811.8822770698246;823.4030328012203;707.2361849202848;817.5054092274178;819.418213068719;820.0323618130603;820.3683163963744;867.2888231650049;868.1231955724701;868.7046404222563;869.6173683579204;869.852125138728;853.2107800284742;855.0252711908367;862.4264863865521;864.5288317866778;864.8572985457392;631.3623240527055;644.687326646576;645.5223134880155;645.9709313080875;647.3720491123603;12533.623991280276;14256.857431425657;14544.70910581793;14581.416743330254;14646.487372941896;13193.538226162038;13481.797773033413;13566;13676.65808354789;13835.861641383557;9108.271338292921;11265.436728163559;11358.03456706181;11432.085433165326;11591.072714182845;3168.98415507921;3467;3595.856165753367;3657.65252301031;3674.3753561894273;58328.708356457944;75967.24032759656;78213.82679259816;99882.001179988;100032.49983750035;10145.746356341151;10349.223808214414;10424.739381515372;10424.791504169874;10747.731306717395;18986.81013189878;19078.713819292723;19181;19884.50288742793;20728.067236974373;7618.390528757686;7635.809104772425;7637.2362763724295;7766.495177813537;7796.571188584682;4183.3515805050265;4221.535631080578;4227.682923780729;4266.5946735060115;4429.955700442954;3308.602967643898;3382.5602671652528;3443.345764304774;3445.8621655133516;3448.1552019755322;627.7614506487597;643.6170478565247;656.5010591950057;664.378805816563;670.8591195848829;595.3480938372476;615.5045188623201;637.2289529669059;644.1625886347823;646.6895889972745;7715.738068369055;8957.776055598662;9014.819703605892;9018.278537716968;9166.495842728715;594.349187639534;598.2701104652317;603.2670305578655;628.7736414890636;651.7023859272099;3947.9537922450572;4185.539590645027;4425.933610995838;4456.77716114193;4460.754658493749;67607.3239267601;98040.50979745056;116763;119487.40256298662;119869;64227.715445690825;64658.53414658496;86991.56504217438;101048.49475752574;101486;90682;91595.08404915866;91650.54174729083;92380.53809731043;93982.53008735049;8267.379946503916;8627.95686021591;8628.870566941376;8716.78208044804;8907.688230911886;45080.77459612681;57500;58023.709881450326;59128;60158.65954181656;77209;78520;79020.60489697514;80474.3905121906;81070;10687.839682404614;11025.669229923114;11042.944785276344;11066.225364224427;11107.333559986426;12018.939905300418;12130.878691213327;12256.571020014257;12389.256644601353;12401.069919756235;11582.478788454708;11744.236624619372;11744.647660570197;11819.054475641757;12047.086074862382;11301.71745706364;11582;11706.239094458833;11778.705532361759;12395.070369722127;10893.237473376788;12313.69215769577;12745.10784245094;12901.032422568533;13224;10445.477726113815;10675.786484270426;10935.23453358289;11265.098792096776;11426.25729327591;10361.533730981973;10970.945145274223;11159.888401115884;11201.775964480828;11286.830697539708 10:18:43 INFO - PROCESS | 1636 | __end_tp_report 10:18:43 INFO - PROCESS | 1636 | __start_cc_report 10:18:43 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,367 10:18:43 INFO - PROCESS | 1636 | __end_cc_report 10:18:43 INFO - PROCESS | 1636 | __startTimestamp1484331523812__endTimestamp 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | ------- Summary: start ------- 10:18:43 INFO - PROCESS | 1636 | Number of tests: 6 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5495.86 Median:5678.00 stddev:639.50 (11.3%) stddev-sans-first:616.67 10:18:43 INFO - PROCESS | 1636 | Values: 3248.3 3288.6 3319.1 3319.4 3325.4 5406.5 5410.1 5420.9 5460.3 5522.6 5499.3 5531.9 5540.2 5552.3 5553.7 4996.2 5004.5 5116.0 5131.0 5176.3 5045.8 5071.9 5113.7 5116.9 5143.8 4870.8 4914.8 5030.3 5067.4 5082.4 3982.8 4290.2 4970.8 4978.1 5001.3 4138.9 4901.1 4923.8 4930.4 5013.5 5109.5 5403.6 5481.6 5484.9 5493.5 5917.0 5983.0 5997.2 6018.2 6059.0 6068.3 6079.3 6097.0 6121.0 6127.4 5448.9 5490.6 5511.8 5614.9 5630.4 6116.4 6154.3 6162.2 6167.8 6179.2 5822.9 5826.8 5846.0 5847.0 5857.3 5793.6 5883.5 5952.2 5977.9 5981.9 4833.5 5834.5 5864.1 5871.5 5993.6 5920.5 5927.4 5935.3 5966.0 5974.9 4770.4 4787.6 4793.4 4835.0 4895.8 4254.9 4281.9 4301.7 4308.2 4397.3 4550.6 5476.7 5644.9 5664.2 5674.9 6065.5 6085.3 6091.0 6091.1 6095.7 5461.4 5567.4 5575.6 5587.0 5600.5 5216.6 5240.5 5243.0 5254.1 5263.7 6232.6 6275.0 6276.0 6283.4 6303.4 4940.6 5749.7 5779.9 5973.5 6030.4 6256.6 6260.4 6293.0 6295.2 6295.7 5911.0 5915.8 5916.4 5927.9 5941.6 5794.3 5821.7 5888.3 5933.8 6010.0 5570.6 5682.6 5760.7 5761.7 5788.8 5734.0 5751.5 5764.3 5781.7 5802.2 5508.7 5515.6 5521.3 5707.9 5716.7 5825.7 5838.4 5848.1 5861.1 5886.8 4764.2 5678.0 5698.0 5704.4 5724.3 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:71410.21 Median:21738.80 stddev:158220.77 (727.8%) stddev-sans-first:158575.00 10:18:43 INFO - PROCESS | 1636 | Values: 481.9 482.6 483.6 519.7 526.1 35314.6 38134.8 39555.6 39625.2 39720.8 38794.4 41670.8 41682.0 41719.0 41786.0 9362.1 9449.8 9470.1 9515.0 9525.4 8044.7 9520.8 9556.3 9556.7 9572.6 8376.5 9014.7 9484.2 9537.4 9540.8 8698.7 9570.5 9571.3 9577.4 9577.5 9415.5 9420.9 9484.5 9499.0 9523.7 38876.8 38997.0 39223.2 39240.0 39312.8 3442.6 3529.2 3812.0 3940.2 3977.6 28753.1 29243.4 29352.6 29667.3 30224.2 3173.7 3193.5 3212.9 3215.3 3303.4 522558.0 525829.0 529312.0 529395.0 532710.0 50149.0 62066.4 62099.7 62393.0 62940.7 54826.7 64126.7 65574.7 65586.7 65679.3 16136.7 16372.8 16391.8 16402.4 16414.0 12264.0 12399.5 12499.0 12506.1 12512.4 17497.0 18636.8 18744.8 18897.3 19436.7 7099.4 7136.9 7510.5 7690.8 7733.6 703.8 935.2 957.0 1020.8 1051.7 29190.4 29663.0 30028.0 30321.7 30368.8 909.0 997.3 1064.3 1069.2 1090.4 15116.4 15212.9 15429.9 15491.0 15699.6 650188.0 655758.0 656613.0 657896.0 658670.0 56294.7 56635.7 56639.4 56710.7 56911.3 1323.6 1347.0 1366.1 1366.4 1369.0 28787.0 29313.9 29698.9 30240.1 30344.0 50201.0 50531.0 50623.0 50656.5 50837.0 576765.0 579958.0 584687.0 587486.0 587605.0 25780.7 26422.3 26458.9 26658.9 26707.3 37239.9 45628.3 45860.0 45861.3 46492.8 31809.8 38375.8 38631.0 38741.6 40012.0 27524.7 38247.4 38510.4 39186.2 39537.4 32030.8 32608.8 37182.4 37274.8 38343.0 27722.0 30498.0 33947.8 34201.0 34262.3 24641.5 25229.4 25695.7 25707.6 26079.7 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:32347.64 Median:18851.58 stddev:86776.11 (460.3%) stddev-sans-first:86986.24 10:18:43 INFO - PROCESS | 1636 | Values: 329.9 341.2 374.7 382.4 398.3 16213.4 18207.5 18815.9 19631.7 19653.3 19440.9 19513.0 20260.0 20272.7 20366.6 5639.3 5782.5 5784.7 5881.6 5891.9 5462.6 5740.8 5796.0 5862.9 5868.9 5798.7 5818.7 5836.2 5840.5 5844.2 5722.8 6420.7 6454.1 6466.8 6473.4 5449.3 5639.0 5698.5 5702.4 5710.2 12869.0 12923.7 12949.0 13142.8 13151.8 2029.2 2138.5 2212.4 2298.9 2350.6 9685.7 9995.6 10228.3 12273.5 14515.0 1737.7 1761.0 1830.3 1862.9 1876.5 119165.8 128847.7 129853.4 132311.3 136004.0 11491.3 11619.2 11724.9 11743.5 11750.0 14408.7 14527.2 14532.0 14536.9 14575.1 7957.6 8142.9 8156.4 8175.6 8241.0 6469.7 7922.8 7924.9 7973.7 8001.2 4010.8 4351.7 4503.7 4514.9 4519.0 1864.7 1903.6 1905.8 1909.6 1910.2 835.4 862.2 862.9 915.0 959.4 20023.6 20928.8 20981.7 21048.2 21357.9 882.2 896.8 915.4 931.7 955.9 10345.3 11173.0 11186.4 11250.8 11258.8 451002.0 451389.0 452796.0 545415.0 625199.0 132172.0 132752.0 139865.3 141224.0 141987.3 933.5 953.4 989.0 992.6 992.7 18625.8 19174.9 19241.8 19374.3 19424.7 37362.0 43965.8 51858.7 53350.9 54801.5 63225.1 76219.2 77401.6 79511.8 80221.6 7872.6 7940.3 8150.6 8262.3 8278.6 10271.0 11885.9 12244.1 12608.7 12847.2 8629.7 9371.9 10883.3 11093.9 11263.8 10638.0 10662.5 10696.2 11043.4 11056.7 10834.1 10964.9 11020.9 11369.6 11383.3 7086.2 7603.1 7911.4 8168.8 8172.0 9340.7 9501.2 10752.6 10756.6 10944.3 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5412.90 Median:5097.98 stddev:2957.11 (58.0%) stddev-sans-first:2948.53 10:18:43 INFO - PROCESS | 1636 | Values: 1208.9 1443.8 1556.8 1728.3 1733.5 7450.6 7701.0 7960.9 8457.5 8467.7 7913.6 8206.4 8224.5 8318.5 8341.5 2301.8 2317.5 2333.9 2336.5 2347.3 2325.4 2342.3 2360.4 2368.2 2368.9 2307.8 2312.5 2340.0 2340.7 2345.0 2297.1 2301.5 2318.6 2325.8 2327.7 2321.1 2342.6 2348.0 2349.1 2353.5 8238.0 8341.5 8389.1 8397.4 8416.8 4950.8 5087.3 5096.9 5099.1 5102.1 3240.3 3385.9 3489.0 3549.0 3554.9 1020.9 1042.8 1076.8 1081.9 1099.3 10774.4 10776.8 10849.9 10867.6 10890.1 4510.3 4671.3 4744.1 4789.1 4793.6 9447.7 9448.2 9456.3 9460.2 9474.7 5153.8 5192.8 5210.4 5213.8 5217.1 4241.6 4267.4 4699.9 4756.9 4778.3 3764.9 3811.6 3867.0 3908.0 3911.3 1064.5 1254.8 1263.6 1269.7 1272.8 887.5 893.3 900.6 901.3 904.0 4871.8 5529.0 5653.5 5701.7 5802.4 686.7 817.9 825.3 936.6 974.4 3367.8 3686.0 3783.2 3807.9 3899.9 8951.5 10020.0 10126.7 10170.9 10258.4 8163.3 9155.7 10054.2 10110.1 10124.4 7813.0 7932.4 7966.6 7982.2 7984.1 5553.3 5564.5 5585.9 5605.3 5628.5 9255.5 9264.4 9364.3 9397.9 9425.8 9116.1 9550.0 9581.7 9584.5 9616.1 5713.2 6242.7 6441.3 6627.1 6850.0 7468.3 8064.4 8073.6 8084.3 8087.6 7057.1 7189.5 7191.4 7217.5 7306.2 7022.8 7050.4 7134.9 7161.9 7275.0 6631.3 6806.0 6807.6 6809.1 6812.3 4817.5 4858.7 4867.2 4886.5 4899.8 4150.8 4682.7 4761.3 4848.2 4905.3 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4352.20 Median:4724.24 stddev:1347.20 (28.5%) stddev-sans-first:1316.27 10:18:43 INFO - PROCESS | 1636 | Values: 304.1 305.5 313.2 315.1 317.3 4207.3 4429.3 5185.1 5454.4 5574.6 4203.0 4437.1 5018.1 5120.1 5383.9 3601.8 3843.3 4113.6 4122.1 4139.3 3421.6 3797.6 3954.1 4146.9 4150.8 3665.2 3988.4 3988.7 4031.5 4032.5 3158.4 3417.0 3494.9 4009.4 4095.2 2614.8 3484.0 3501.9 3503.4 3521.6 4137.5 4258.7 4540.5 4644.4 5204.0 4159.9 4375.7 4454.9 4457.0 4603.8 4628.4 5510.9 5581.4 5619.9 5656.7 3343.9 3643.6 3715.3 3835.2 3837.9 4843.7 5130.6 5456.6 5785.3 5906.8 3058.4 3287.3 3680.8 3834.0 3851.0 3467.6 3918.2 3923.2 3930.5 3949.7 4229.6 4483.2 4559.0 4588.7 4616.7 4933.2 5705.9 5712.7 5715.7 5720.3 1027.2 1234.0 1238.7 1276.7 1287.0 858.6 871.4 878.5 882.9 883.3 5094.6 5141.8 5154.8 5178.5 5181.5 4349.6 5499.5 5516.4 5548.0 5605.5 4502.3 4726.5 4803.7 5007.2 5130.9 2668.5 2875.9 2886.6 2908.8 2917.6 4672.5 4673.1 5319.0 5761.9 5765.0 4665.0 5665.3 5676.3 5687.3 5730.7 4928.2 5686.3 5720.4 5747.5 5749.6 5289.4 5515.3 5562.8 5571.6 5622.2 4534.9 4900.1 5435.0 5516.5 5530.9 5433.0 5685.5 5725.8 5735.7 5744.6 3158.2 3198.5 3634.1 3700.2 3733.9 4810.4 4949.5 5281.9 5303.6 5514.4 4131.4 4753.4 4921.5 5072.2 5303.5 4722.0 5159.1 5280.3 5323.7 5333.4 4406.0 5252.3 5264.0 5268.9 5456.4 4714.4 4957.5 4969.8 5711.8 5734.0 4597.4 4708.6 5138.8 5316.4 5361.8 10:18:43 INFO - PROCESS | 1636 | 10:18:43 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20080.89 Median:4346.26 stddev:29732.16 (684.1%) stddev-sans-first:29779.95 10:18:43 INFO - PROCESS | 1636 | Values: 698.9 712.1 748.1 782.3 798.6 12212.5 13739.2 14269.4 14479.9 14520.7 13045.5 14185.3 14728.7 14776.2 15170.0 651.9 673.9 799.6 811.9 823.4 707.2 817.5 819.4 820.0 820.4 867.3 868.1 868.7 869.6 869.9 853.2 855.0 862.4 864.5 864.9 631.4 644.7 645.5 646.0 647.4 12533.6 14256.9 14544.7 14581.4 14646.5 13193.5 13481.8 13566.0 13676.7 13835.9 9108.3 11265.4 11358.0 11432.1 11591.1 3169.0 3467.0 3595.9 3657.7 3674.4 58328.7 75967.2 78213.8 99882.0 100032.5 10145.7 10349.2 10424.7 10424.8 10747.7 18986.8 19078.7 19181.0 19884.5 20728.1 7618.4 7635.8 7637.2 7766.5 7796.6 4183.4 4221.5 4227.7 4266.6 4430.0 3308.6 3382.6 3443.3 3445.9 3448.2 627.8 643.6 656.5 664.4 670.9 595.3 615.5 637.2 644.2 646.7 7715.7 8957.8 9014.8 9018.3 9166.5 594.3 598.3 603.3 628.8 651.7 3948.0 4185.5 4425.9 4456.8 4460.8 67607.3 98040.5 116763.0 119487.4 119869.0 64227.7 64658.5 86991.6 101048.5 101486.0 90682.0 91595.1 91650.5 92380.5 93982.5 8267.4 8628.0 8628.9 8716.8 8907.7 45080.8 57500.0 58023.7 59128.0 60158.7 77209.0 78520.0 79020.6 80474.4 81070.0 10687.8 11025.7 11042.9 11066.2 11107.3 12018.9 12130.9 12256.6 12389.3 12401.1 11582.5 11744.2 11744.6 11819.1 12047.1 11301.7 11582.0 11706.2 11778.7 12395.1 10893.2 12313.7 12745.1 12901.0 13224.0 10445.5 10675.8 10935.2 11265.1 11426.3 10361.5 10970.9 11159.9 11201.8 11286.8 10:18:43 INFO - PROCESS | 1636 | -------- Summary: end -------- 10:18:43 INFO - PROCESS | 1636 | 10:18:44 INFO - TEST-INFO | 1636: exit 0 10:18:44 INFO - TEST-OK | dromaeo_css | took 1115301ms 10:18:44 INFO - TEST-START | kraken 10:18:44 INFO - operating with platform_type : mac_ 10:18:44 INFO - Initialising browser for kraken test... 10:18:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/tmplu0lGI/profile http://localhost:49235/getInfo.html) 10:18:47 INFO - PROCESS | 1647 | 2017-01-13 10:18:47.340 plugin-container[1648:9969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 10:18:47 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:47 INFO - PROCESS | 1647 | 2017-01-13 10:18:47.343 plugin-container[1648:9969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.CFPasteboardClient' 10:18:47 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:47 INFO - PROCESS | 1647 | 2017-01-13 10:18:47.343 plugin-container[1648:9969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:18:47 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:18:47 INFO - PROCESS | 1647 | colorDepth:24 10:18:47 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:18:47 INFO - PROCESS | 1647 | __metrics 10:18:47 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:18:48 INFO - TEST-INFO | 1647: exit 0 10:18:48 INFO - Browser initialized. 10:18:48 INFO - Running cycle 1/1 for kraken test... 10:18:48 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/tmplu0lGI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:51 INFO - PROCESS | 1649 | 2017-01-13 10:18:51.119 plugin-container[1650:10094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:18:51 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:51 INFO - PROCESS | 1649 | 2017-01-13 10:18:51.120 plugin-container[1650:10094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 10:18:51 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:51 INFO - PROCESS | 1649 | 2017-01-13 10:18:51.120 plugin-container[1650:10094] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:00 INFO - PROCESS | 1649 | RSS: Main: 228741120 10:19:00 INFO - PROCESS | 1649 | 10:19:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:04 INFO - PROCESS | 1649 | RSS: Main: 241197056 10:19:04 INFO - PROCESS | 1649 | 10:19:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 10:19:07 INFO - PROCESS | 1649 | RSS: Main: 249241600 10:19:07 INFO - PROCESS | 1649 | 10:19:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 10:19:11 INFO - PROCESS | 1649 | RSS: Main: 252981248 10:19:11 INFO - PROCESS | 1649 | 10:19:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 10:19:14 INFO - PROCESS | 1649 | RSS: Main: 253247488 10:19:14 INFO - PROCESS | 1649 | 10:19:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:17 INFO - PROCESS | 1649 | RSS: Main: 253300736 10:19:17 INFO - PROCESS | 1649 | 10:19:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:20 INFO - PROCESS | 1649 | RSS: Main: 257945600 10:19:20 INFO - PROCESS | 1649 | 10:19:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:24 INFO - PROCESS | 1649 | RSS: Main: 257871872 10:19:24 INFO - PROCESS | 1649 | 10:19:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 10:19:27 INFO - PROCESS | 1649 | RSS: Main: 258125824 10:19:27 INFO - PROCESS | 1649 | 10:19:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:29 INFO - PROCESS | 1649 | RSS: Main: 257241088 10:19:29 INFO - PROCESS | 1649 | 10:19:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:32 INFO - PROCESS | 1649 | RSS: Main: 257236992 10:19:32 INFO - PROCESS | 1649 | 10:19:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:35 INFO - PROCESS | 1649 | RSS: Main: 257236992 10:19:35 INFO - PROCESS | 1649 | 10:19:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:19:38 INFO - PROCESS | 1649 | RSS: Main: 257339392 10:19:38 INFO - PROCESS | 1649 | 10:19:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:19:42 INFO - PROCESS | 1649 | RSS: Main: 257720320 10:19:42 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 10:19:44 INFO - PROCESS | 1649 | RSS: Main: 259506176 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | __start_tp_report 10:19:44 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:19:44 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:19:44 INFO - PROCESS | 1649 | |i|pagename|runs| 10:19:44 INFO - PROCESS | 1649 | |0;ai-astar;109;120;148;175;155;117;117;118;119;114 10:19:44 INFO - PROCESS | 1649 | |1;audio-beat-detection;186;128;114;147;112;112;117;109;119;149 10:19:44 INFO - PROCESS | 1649 | |2;audio-dft;197;204;246;196;196;197;196;280;249;197 10:19:44 INFO - PROCESS | 1649 | |3;audio-fft;79;62;84;103;57;58;59;82;76;59 10:19:44 INFO - PROCESS | 1649 | |4;audio-oscillator;86;71;87;71;68;71;89;70;72;87 10:19:44 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;136;133;91;88;101;88;91;89;92;88 10:19:44 INFO - PROCESS | 1649 | |6;imaging-darkroom;105;128;96;94;96;97;96;96;96;96 10:19:44 INFO - PROCESS | 1649 | |7;imaging-desaturate;91;93;101;109;89;91;91;91;90;87 10:19:44 INFO - PROCESS | 1649 | |8;json-parse-financial;56;56;55;59;61;59;60;59;58;59 10:19:44 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;75;73;80;93;104;69;70;71;73;73 10:19:44 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;87;83;84;82;86;84;86;87;85;102 10:19:44 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;165;154;137;139;170;159;161;147;185;142 10:19:44 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;165;167;167;166;206;226;183;220;164;215 10:19:44 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;69;68;75;82;89;68;68;84;86;90 10:19:44 INFO - PROCESS | 1649 | __end_tp_report 10:19:44 INFO - PROCESS | 1649 | __start_cc_report 10:19:44 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,950 10:19:44 INFO - PROCESS | 1649 | __end_cc_report 10:19:44 INFO - PROCESS | 1649 | __startTimestamp1484331584632__endTimestamp 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:19:44 INFO - PROCESS | 1649 | Number of tests: 14 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:129.20 Median:119.50 stddev:22.03 (18.4%) stddev-sans-first:22.12 10:19:44 INFO - PROCESS | 1649 | Values: 109.0 120.0 148.0 175.0 155.0 117.0 117.0 118.0 119.0 114.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:123.50 stddev:24.54 (19.9%) stddev-sans-first:15.20 10:19:44 INFO - PROCESS | 1649 | Values: 186.0 128.0 114.0 147.0 112.0 112.0 117.0 109.0 119.0 149.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:215.80 Median:200.50 stddev:30.75 (15.3%) stddev-sans-first:31.86 10:19:44 INFO - PROCESS | 1649 | Values: 197.0 204.0 246.0 196.0 196.0 197.0 196.0 280.0 249.0 197.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:71.90 Median:69.00 stddev:15.38 (22.3%) stddev-sans-first:16.10 10:19:44 INFO - PROCESS | 1649 | Values: 79.0 62.0 84.0 103.0 57.0 58.0 59.0 82.0 76.0 59.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:79.00 stddev:8.74 (11.1%) stddev-sans-first:8.67 10:19:44 INFO - PROCESS | 1649 | Values: 86.0 71.0 87.0 71.0 68.0 71.0 89.0 70.0 72.0 87.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:88.50 stddev:18.75 (21.2%) stddev-sans-first:14.58 10:19:44 INFO - PROCESS | 1649 | Values: 136.0 133.0 91.0 88.0 101.0 88.0 91.0 89.0 92.0 88.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:96.00 stddev:10.27 (10.7%) stddev-sans-first:10.74 10:19:44 INFO - PROCESS | 1649 | Values: 105.0 128.0 96.0 94.0 96.0 97.0 96.0 96.0 96.0 96.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:91.00 stddev:6.63 (7.3%) stddev-sans-first:6.98 10:19:44 INFO - PROCESS | 1649 | Values: 91.0 93.0 101.0 109.0 89.0 91.0 91.0 91.0 90.0 87.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:59.00 stddev:1.93 (3.3%) stddev-sans-first:1.88 10:19:44 INFO - PROCESS | 1649 | Values: 56.0 56.0 55.0 59.0 61.0 59.0 60.0 59.0 58.0 59.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:73.00 stddev:11.46 (15.7%) stddev-sans-first:12.10 10:19:44 INFO - PROCESS | 1649 | Values: 75.0 73.0 80.0 93.0 104.0 69.0 70.0 71.0 73.0 73.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.50 stddev:5.66 (6.6%) stddev-sans-first:6.00 10:19:44 INFO - PROCESS | 1649 | Values: 87.0 83.0 84.0 82.0 86.0 84.0 86.0 87.0 85.0 102.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:160.00 stddev:15.21 (9.5%) stddev-sans-first:15.78 10:19:44 INFO - PROCESS | 1649 | Values: 165.0 154.0 137.0 139.0 170.0 159.0 161.0 147.0 185.0 142.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.90 Median:194.50 stddev:25.86 (13.3%) stddev-sans-first:26.06 10:19:44 INFO - PROCESS | 1649 | Values: 165.0 167.0 167.0 166.0 206.0 226.0 183.0 220.0 164.0 215.0 10:19:44 INFO - PROCESS | 1649 | 10:19:44 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.90 Median:83.00 stddev:9.26 (11.2%) stddev-sans-first:9.24 10:19:44 INFO - PROCESS | 1649 | Values: 69.0 68.0 75.0 82.0 89.0 68.0 68.0 84.0 86.0 90.0 10:19:44 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:19:44 INFO - PROCESS | 1649 | 10:19:45 INFO - TEST-INFO | 1649: exit 0 10:19:45 INFO - TEST-OK | kraken | took 61097ms 10:19:45 INFO - SUITE-END | took 1176s 10:19:45 INFO - Completed test suite (00:19:36) 10:19:45 INFO - javascript benchmark 10:19:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7040.133749123263, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3248.2529018325768, 3288.572485576875, 3319.0706602151404, 3319.352726218385, 3325.418051840928, 5406.486383793543, 5410.1073322901775, 5420.91868621971, 5460.262864513289, 5522.585806064551, 5499.312585926759, 5531.917021244684, 5540.168974653804, 5552.278203833506, 5553.666779993203, 4996.175631020896, 5004.524570165829, 5115.976804639087, 5131.0, 5176.275321454999, 5045.823396181135, 5071.949280507185, 5113.693178409308, 5116.872078198037, 5143.7685304161405, 4870.805167793319, 4914.803407863681, 5030.2957585938, 5067.39191297043, 5082.390113186404, 3982.8406863725418, 4290.184864875664, 4970.801167953277, 4978.054169707781, 5001.324821149135, 4138.896527586804, 4901.093297739894, 4923.753812309406, 4930.4330002049865, 5013.473585273567, 5109.463506331819, 5403.621746477748, 5481.643693159932, 5484.890302193934, 5493.533049690781, 5916.9941110011405, 5982.970085149546, 5997.160397544348, 6018.21763170785, 6058.969705151446, 6068.271807383098, 6079.270487541479, 6097.024476083805, 6121.0, 6127.44852963231, 5448.945510544923, 5490.615656903979, 5511.779528818883, 5614.85962850932, 5630.436956304377, 6116.3577824328695, 6154.261488621304, 6162.22972128484, 6167.814965551041, 6179.16581261533, 5822.854428639318, 5826.79606213789, 5846.0, 5847.0, 5857.26784151981, 5793.565482588739, 5883.470487656132, 5952.196453478811, 5977.910331345033, 5981.850453738604, 4833.468318484964, 5834.504067154339, 5864.091065884794, 5871.500922421556, 5993.550483713739, 5920.496757775637, 5927.436893495111, 5935.2877654681715, 5966.015607424813, 5974.910376344358, 4770.356001939694, 4787.640926930494, 4793.352897358882, 4835.008823191238, 4895.755212239374, 4254.87235382939, 4281.935770963438, 4301.677374196947, 4308.202982448257, 4397.34039894012, 4550.590446859798, 5476.67139971603, 5644.943550564524, 5664.235328230636, 5674.943250567524, 6065.484433823086, 6085.330613632497, 6091.02543593023, 6091.08633704939, 6095.664738439428, 5461.42655021225, 5567.387587365387, 5575.581831362664, 5587.022271102541, 5600.467955544217, 5216.60875434344, 5240.4759524047295, 5243.0, 5254.054270231395, 5263.6578622389425, 6232.56372053961, 6275.027370757449, 6276.0, 6283.403076707706, 6303.432691057827, 4940.5800506955875, 5749.683767392834, 5779.884402312015, 5973.522118230444, 6030.3668114847305, 6256.624602523863, 6260.40526150015, 6292.968535157295, 6295.213098362705, 6295.685215739103, 5910.970445147746, 5915.792947246826, 5916.408359164049, 5927.940720592911, 5941.643501389931, 5794.333651630117, 5821.679807610452, 5888.293404791452, 5933.792317268875, 6010.0083486224285, 5570.582206334622, 5682.573806964577, 5760.740766665594, 5761.654300741969, 5788.82633520995, 5733.967885780433, 5751.4536119068625, 5764.337101233412, 5781.739821707949, 5802.1586869902785, 5508.697021663847, 5515.641483303572, 5521.337439507285, 5707.914381284367, 5716.685582293014, 5825.679587622722, 5838.386969368155, 5848.122781582627, 5861.0915308127305, 5886.793962211302, 4764.190087685171, 5678.0, 5697.971510142423, 5704.3725190228215, 5724.313082430135], "value": 5456.200734247554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.930120132581, 482.6114977443158, 483.5647916874812, 519.7474087449248, 526.1292560811853, 35314.64685353152, 38134.80932595336, 39555.604443955555, 39625.20749585007, 39720.808375748704, 38794.4180837287, 41670.791646041806, 41682.0, 41719.0, 41786.0, 9362.110599493037, 9449.818772653418, 9470.052994700543, 9515.0, 9525.380850244712, 8044.718434854782, 9520.809583808319, 9556.283278754086, 9556.713298601018, 9572.56923438447, 8376.53929033903, 9014.68448604299, 9484.19384352331, 9537.380070295443, 9540.80918381632, 8698.695545655903, 9570.521473926345, 9571.28215383849, 9577.425354478684, 9577.521123943845, 9415.529223538795, 9420.905790942072, 9484.525773711286, 9499.0, 9523.714288571355, 38876.80561597202, 38997.02507437309, 39223.215535689415, 39240.01899952495, 39312.803435982925, 3442.569678790151, 3529.205928666055, 3812.0, 3940.192260586573, 3977.6022397759994, 28753.13740587765, 29243.415131697246, 29352.559711604343, 29667.258318541994, 30224.24439389011, 3173.690963947939, 3193.457112290916, 3212.8714851405916, 3215.340855124694, 3303.438415469376, 522558.0, 525829.0, 529312.0, 529395.0, 532710.0, 50149.0, 62066.44833879189, 62099.6895015522, 62393.0, 62940.685296573225, 54826.72586637041, 64126.67936660287, 65574.67212664001, 65586.67206663937, 65679.34320656827, 16136.67726645484, 16372.754408683879, 16391.83608163927, 16402.425915092914, 16414.015159090493, 12264.0, 12399.504019839285, 12499.0, 12506.062045346453, 12512.374381280899, 17496.985227615278, 18636.813631863508, 18744.81255187458, 18897.33859314945, 19436.70844937327, 7099.3965512930945, 7136.929460580955, 7510.549367037996, 7690.807729806687, 7733.613319334123, 703.8281261699213, 935.2004036548789, 956.9665061722807, 1020.8162530744389, 1051.7423231308226, 29190.416191676046, 29663.0, 30028.0, 30321.69678303233, 30368.84815575908, 908.9909100908907, 997.2769741937037, 1064.3188359449957, 1069.2194697870443, 1090.3621381491757, 15116.395344186329, 15212.923935380473, 15429.922850385676, 15491.0, 15699.607509812346, 650188.0, 655758.0, 656613.0, 657896.0, 658670.0, 56294.71852640792, 56635.716821415626, 56639.43360566341, 56710.7164464175, 56911.29266122023, 1323.602919124259, 1346.9964876167123, 1366.0983750724474, 1366.4124426496503, 1368.9726205475836, 28786.992455263964, 29313.85343073356, 29698.851505742332, 30240.09279721612, 30344.0, 50201.0, 50531.0, 50623.0, 50656.49343506665, 50837.0, 576765.0, 579958.0, 584687.0, 587486.0, 587605.0, 25780.742192578586, 26422.339441513348, 26458.86770566135, 26658.866705666347, 26707.33231669146, 37239.88280351594, 45628.31557526573, 45860.0, 45861.31208031815, 46492.7675361621, 31809.840950796024, 38375.80812095922, 38631.0, 38741.61258387493, 40012.0, 27524.724752753016, 38247.42628860625, 38510.42234366543, 39186.216275674895, 39537.406938896514, 32030.839845801554, 32608.83695581507, 37182.44226336553, 37274.81362593278, 38343.04142396463, 27722.0, 30498.0, 33947.83026084854, 34201.0, 34262.31475370429, 24641.521508709535, 25229.369265768502, 25695.743042569335, 25707.614385784604, 26079.739202607732], "value": 19703.26786738225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.88783813503403, 341.16755117513253, 374.74330083892534, 382.41108692613375, 398.3308042488618, 16213.351465941378, 18207.453776386712, 18815.905920470395, 19631.705524417146, 19653.312134075273, 19440.90279548604, 19513.0, 20260.0, 20272.695909561367, 20366.592668146666, 5639.323281206251, 5782.53739700825, 5784.710764461781, 5881.647101173922, 5891.911621325684, 5462.644928079662, 5740.79907203248, 5796.0, 5862.9413705863035, 5868.914250863604, 5798.65208087514, 5818.650880947157, 5836.182934389189, 5840.474357307863, 5844.2402487676545, 5722.771089156428, 6420.743170273184, 6454.064160696673, 6466.838329041788, 6473.446372870524, 5449.291592093003, 5639.041362968305, 5698.458646428583, 5702.372738998707, 5710.200571919934, 12869.034822388357, 12923.67690807728, 12949.0, 13142.802857957138, 13151.802722959163, 2029.1984666056971, 2138.5081431270737, 2212.371868445007, 2298.931032069024, 2350.5916421189345, 9685.709428717078, 9995.550200240934, 10228.335158214692, 12273.509059637692, 14515.0, 1737.6698427298772, 1760.9647807043787, 1830.331928845975, 1862.9254829806791, 1876.502726777405, 119165.80834191634, 128847.7115228845, 129853.35073324667, 132311.33844330814, 136003.999999999, 11491.310521368745, 11619.244749091282, 11724.88275117238, 11743.530258789553, 11750.0, 14408.711825763427, 14527.201003944885, 14531.982761206615, 14536.927315363568, 14575.052621579563, 7957.641906114239, 8142.85999960006, 8156.429049966446, 8175.5503447309775, 8240.958795205986, 6469.708863101077, 7922.841543169219, 7924.920750792534, 7973.681052757825, 8001.239882211181, 4010.7994600270454, 4351.6518678505645, 4503.707259028152, 4514.864554063383, 4518.9548104518535, 1864.7016477363827, 1903.60085337277, 1905.7998910114238, 1909.6467153576632, 1910.235905637756, 835.3985130705883, 862.2369203255164, 862.8921384826897, 914.9843673522385, 959.4195511715451, 20023.59952800936, 20928.79071209299, 20981.68527472089, 21048.15807367688, 21357.932103394767, 882.188386684252, 896.8385690575759, 915.3522764542787, 931.7065124485765, 955.861400096982, 10345.27583069193, 11173.0, 11186.384748838893, 11250.774984500264, 11258.831117533244, 451002.0, 451389.0, 452796.0, 545415.0, 625199.0, 132172.0, 132752.0, 139865.30067349802, 141224.0, 141987.29006355107, 933.5192375926404, 953.3517208298424, 988.9950550247347, 992.6277645882794, 992.6846927820523, 18625.789323693916, 19174.904125479283, 19241.807581924, 19374.321898733477, 19424.708629370853, 37362.0, 43965.78017109894, 51858.74070629623, 53350.932981340935, 54801.45198547964, 63225.051624224754, 76219.23780762122, 77401.61299193469, 79511.80732288904, 80221.59889200516, 7872.567008814341, 7940.325072368682, 8150.551719655476, 8262.339012878834, 8278.634025385985, 10271.0, 11885.881141188478, 12244.142909996572, 12608.693493040126, 12847.164934279243, 8629.654813807378, 9371.859422108537, 10883.292585981886, 11093.944530277297, 11263.831042534532, 10638.042576167873, 10662.46687665598, 10696.197785165989, 11043.39261340634, 11056.723581910517, 10834.079103275995, 10964.890351096386, 11020.944895275472, 11369.602063927723, 11383.260088094248, 7086.220515743161, 7603.0876294844975, 7911.367090632851, 8168.7957801055445, 8171.978502687164, 9340.71977840666, 9501.239900807781, 10752.623658171926, 10756.623518176826, 10944.343339399662], "value": 9215.814708678523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.9335086570238, 1443.7978682984385, 1556.8287488376277, 1728.3086765293874, 1733.4626265857585, 7450.552966821994, 7701.0, 7960.880586791195, 8457.53483558404, 8467.661293548259, 7913.643886025128, 8206.425550211488, 8224.547649879254, 8318.459300145501, 8341.54121523316, 2301.8158547316216, 2317.455397981473, 2333.9183128590503, 2336.4976530046038, 2347.3099368454687, 2325.395397196724, 2342.2856028911174, 2360.433495960974, 2368.1948137633203, 2368.9289321320302, 2307.792298693117, 2312.5490529346785, 2340.0, 2340.707411573553, 2344.9648255276184, 2297.1385730351117, 2301.4591570980806, 2318.5710643531, 2325.813934885205, 2327.743948165692, 2321.0831721469926, 2342.61346877765, 2347.9647805282934, 2349.119080344871, 2353.51752890657, 8238.0, 8341.541215233221, 8389.119142490074, 8397.37019723517, 8416.789580260482, 4950.752462376867, 5087.3386459759995, 5096.872578185538, 5099.08216521026, 5102.1071312520535, 3240.2709390386976, 3385.8814941477176, 3489.040513858684, 3549.0, 3554.928901421983, 1020.8825985011745, 1042.8435734639866, 1076.7577295108617, 1081.864766904137, 1099.2964502718266, 10774.353538787698, 10776.78446431067, 10849.891501084967, 10867.565297388095, 10890.128789696806, 4510.345999830072, 4671.252599584051, 4744.146053710297, 4789.090072886141, 4793.616510679138, 9447.6693315735, 9448.196903263162, 9456.290778191664, 9460.195883349992, 9474.715758527254, 5153.793848246029, 5192.81825136126, 5210.374755029421, 5213.765380557884, 5217.113090774577, 4241.597048280409, 4267.4025636410925, 4699.929501057487, 4756.9286460703115, 4778.283257511332, 3764.887053388403, 3811.599782022903, 3866.980665096713, 3907.9609203908167, 3911.2959667260166, 1064.462446464546, 1254.7804134276464, 1263.5577547858177, 1269.6825793551611, 1272.7963525835821, 887.4985633117398, 893.2942975049768, 900.6127365232882, 901.2699713232303, 903.9548022598843, 4871.8294859679745, 5528.9723551381985, 5653.462921022497, 5701.71491425427, 5802.4197580241635, 686.7081490366575, 817.887672765039, 825.2778818533783, 936.6487567162314, 974.3812678948776, 3367.7810942288675, 3685.9863537527394, 3783.181529760346, 3807.8667246646787, 3899.883003509899, 8951.507667078244, 10020.0, 10126.696199114038, 10170.898291016994, 10258.43578603169, 8163.3061189798345, 9155.679551215675, 10054.245931555162, 10110.140638045701, 10124.392536447836, 7812.960935195401, 7932.365410767125, 7966.6415011324625, 7982.241687039722, 7984.08183058944, 5553.333599968029, 5564.4713752193475, 5585.888282234414, 5605.271314729059, 5628.493435590816, 9255.537223138681, 9264.39781414206, 9364.344495885223, 9397.906020939976, 9425.811483770423, 9116.1339672731, 9549.952250238983, 9581.712548623553, 9584.472853992818, 9616.08647178517, 5713.22871412354, 6242.687865606792, 6441.259255185713, 6627.105340778933, 6850.006749021264, 7468.290512401313, 8064.395170362129, 8073.5963201838495, 8084.272415482635, 8087.595620219083, 7057.117860267466, 7189.496735228484, 7191.352778249982, 7217.494775365674, 7306.232845551141, 7022.789316320519, 7050.421707929553, 7134.85730285381, 7161.892571611535, 7275.017872587133, 6631.33686631333, 6805.9659701701175, 6807.625580592917, 6809.114815074008, 6812.318768123148, 4817.470078291455, 4858.684185528071, 4867.245576935581, 4886.511348865085, 4899.779509921991, 4150.771707556113, 4682.719036857799, 4761.285807128958, 4848.248521479177, 4905.264210368473], "value": 4390.057109443003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.0695471856119, 305.463910836482, 313.2153954344367, 315.13652591898204, 317.28768913788537, 4207.284761590533, 4429.335599660053, 5185.066687996159, 5454.400015998237, 5574.606348412902, 4203.036859014395, 4437.138045960353, 5018.121828679985, 5120.129577971745, 5383.892322153554, 3601.7658852174654, 3843.2889915365677, 4113.568075352075, 4122.134351786129, 4139.296319625671, 3421.631347461023, 3797.5632802227683, 3954.070793363557, 4146.917061658765, 4150.750954942698, 3665.157013886808, 3988.441618173458, 3988.660963818079, 4031.4759081319535, 4032.495938007749, 3158.4314823331792, 3417.0, 3494.899106781356, 4009.3985902114623, 4095.160492099142, 2614.8038897082606, 3483.9651603483894, 3501.91245218869, 3503.3693935091674, 3521.612622611536, 4137.462129923121, 4258.680598955059, 4540.523245059254, 4644.396228490308, 5203.973980130075, 4159.854405095837, 4375.7374557526655, 4454.866354009385, 4456.997175635456, 4603.792829322655, 4628.421447319085, 5510.889782204374, 5581.358143813432, 5619.915701264485, 5656.6888821115235, 3343.8829640962695, 3643.5521908876503, 3715.349813782604, 3835.2329534093014, 3837.9466985163085, 4843.733594652293, 5130.563902068291, 5456.63584103974, 5785.305763308388, 5906.822795316148, 3058.3883223354974, 3287.309664970383, 3680.760750551259, 3833.971162781427, 3850.9807450962567, 3467.6358982306497, 3918.2359439908946, 3923.214517692413, 3930.469386632768, 3949.6840252779707, 4229.555896630871, 4483.170613436525, 4558.9514411685495, 4588.655850811202, 4616.6537509686905, 4933.235348520985, 5705.857353566194, 5712.714364281769, 5715.742791574306, 5720.342160651495, 1027.198784947742, 1233.993830030844, 1238.6965193527644, 1276.6808297925518, 1286.9935650321875, 858.5833374931414, 871.3813192633174, 878.4597472554333, 882.9337799665051, 883.3419102768479, 5094.624451398102, 5141.820036298712, 5154.845354639367, 5178.498235511659, 5181.53366197044, 4349.608535231781, 5499.50504454601, 5516.393196748354, 5547.972260138674, 5605.523530499871, 4502.3246513023305, 4726.527347265314, 4803.711777293373, 5007.173816320266, 5130.948690513122, 2668.506326329635, 2875.899343522967, 2886.567014947733, 2908.8254704717783, 2917.6207093077765, 4672.462666793294, 4673.111879032375, 5318.994767956093, 5761.88476230473, 5764.971175144181, 4664.976675116603, 5665.263515742927, 5676.290463692038, 5687.260656114761, 5730.656160630376, 4928.180659988538, 5686.322534852228, 5720.427957204246, 5747.482726554546, 5749.579000494699, 5289.391719952101, 5515.255440515479, 5562.831805320766, 5571.60998730085, 5622.240997465454, 4534.931976020296, 4900.093482705711, 5434.972825135849, 5516.531094856982, 5530.866172434722, 5433.044104122434, 5685.459881311269, 5725.770969161354, 5735.684537350486, 5744.626599271034, 3158.2313904213715, 3198.5362122491642, 3634.1459756956565, 3700.243318727565, 3733.9066523337133, 4810.42274927011, 4949.455559888337, 5281.943611277837, 5303.628745987899, 5514.393416724238, 4131.368109596632, 4753.363676787191, 4921.4832442593015, 5072.195853110167, 5303.549198318186, 4721.952780472151, 5159.071367153948, 5280.287161233185, 5323.653962492425, 5333.386660534088, 4405.9559404405545, 5252.317198764136, 5264.0, 5268.917683155397, 5456.399796022355, 4714.434267887876, 4957.504249575158, 4969.801207951641, 5711.771529138788, 5733.971330143323, 4597.42532183477, 4708.552687494683, 5138.820141295037, 5316.388615309289, 5361.758720857649], "value": 3931.4864888886655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.9231184569699, 712.1169749510602, 748.072390236107, 782.2920257167266, 798.5607915646397, 12212.511499540018, 13739.24434156121, 14269.35787889545, 14479.855201447983, 14520.709585808278, 13045.543405980792, 14185.290735463212, 14728.705425891476, 14776.187309697962, 15170.0, 651.8631087471613, 673.9022841687952, 799.5842162075703, 811.8822770698246, 823.4030328012203, 707.2361849202848, 817.5054092274178, 819.418213068719, 820.0323618130603, 820.3683163963744, 867.2888231650049, 868.1231955724701, 868.7046404222563, 869.6173683579204, 869.852125138728, 853.2107800284742, 855.0252711908367, 862.4264863865521, 864.5288317866778, 864.8572985457392, 631.3623240527055, 644.687326646576, 645.5223134880155, 645.9709313080875, 647.3720491123603, 12533.623991280276, 14256.857431425657, 14544.70910581793, 14581.416743330254, 14646.487372941896, 13193.538226162038, 13481.797773033413, 13566.0, 13676.65808354789, 13835.861641383557, 9108.271338292921, 11265.436728163559, 11358.03456706181, 11432.085433165326, 11591.072714182845, 3168.98415507921, 3467.0, 3595.856165753367, 3657.65252301031, 3674.3753561894273, 58328.708356457944, 75967.24032759656, 78213.82679259816, 99882.001179988, 100032.49983750035, 10145.746356341151, 10349.223808214414, 10424.739381515372, 10424.791504169874, 10747.731306717395, 18986.81013189878, 19078.713819292723, 19181.0, 19884.50288742793, 20728.067236974373, 7618.390528757686, 7635.809104772425, 7637.2362763724295, 7766.495177813537, 7796.571188584682, 4183.3515805050265, 4221.535631080578, 4227.682923780729, 4266.5946735060115, 4429.955700442954, 3308.602967643898, 3382.5602671652528, 3443.345764304774, 3445.8621655133516, 3448.1552019755322, 627.7614506487597, 643.6170478565247, 656.5010591950057, 664.378805816563, 670.8591195848829, 595.3480938372476, 615.5045188623201, 637.2289529669059, 644.1625886347823, 646.6895889972745, 7715.738068369055, 8957.776055598662, 9014.819703605892, 9018.278537716968, 9166.495842728715, 594.349187639534, 598.2701104652317, 603.2670305578655, 628.7736414890636, 651.7023859272099, 3947.9537922450572, 4185.539590645027, 4425.933610995838, 4456.77716114193, 4460.754658493749, 67607.3239267601, 98040.50979745056, 116763.0, 119487.40256298662, 119869.0, 64227.715445690825, 64658.53414658496, 86991.56504217438, 101048.49475752574, 101486.0, 90682.0, 91595.08404915866, 91650.54174729083, 92380.53809731043, 93982.53008735049, 8267.379946503916, 8627.95686021591, 8628.870566941376, 8716.78208044804, 8907.688230911886, 45080.77459612681, 57500.0, 58023.709881450326, 59128.0, 60158.65954181656, 77209.0, 78520.0, 79020.60489697514, 80474.3905121906, 81070.0, 10687.839682404614, 11025.669229923114, 11042.944785276344, 11066.225364224427, 11107.333559986426, 12018.939905300418, 12130.878691213327, 12256.571020014257, 12389.256644601353, 12401.069919756235, 11582.478788454708, 11744.236624619372, 11744.647660570197, 11819.054475641757, 12047.086074862382, 11301.71745706364, 11582.0, 11706.239094458833, 11778.705532361759, 12395.070369722127, 10893.237473376788, 12313.69215769577, 12745.10784245094, 12901.032422568533, 13224.0, 10445.477726113815, 10675.786484270426, 10935.23453358289, 11265.098792096776, 11426.25729327591, 10361.533730981973, 10970.945145274223, 11159.888401115884, 11201.775964480828, 11286.830697539708], "value": 7119.461635945735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 120.0, 148.0, 175.0, 155.0, 117.0, 117.0, 118.0, 119.0, 114.0], "value": 129.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 128.0, 114.0, 147.0, 112.0, 112.0, 117.0, 109.0, 119.0, 149.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 204.0, 246.0, 196.0, 196.0, 197.0, 196.0, 280.0, 249.0, 197.0], "value": 215.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 84.0, 103.0, 57.0, 58.0, 59.0, 82.0, 76.0, 59.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 87.0, 71.0, 68.0, 71.0, 89.0, 70.0, 72.0, 87.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 91.0, 88.0, 101.0, 88.0, 91.0, 89.0, 92.0, 88.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 128.0, 96.0, 94.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 101.0, 109.0, 89.0, 91.0, 91.0, 91.0, 90.0, 87.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 59.0, 61.0, 59.0, 60.0, 59.0, 58.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 80.0, 93.0, 104.0, 69.0, 70.0, 71.0, 73.0, 73.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 84.0, 82.0, 86.0, 84.0, 86.0, 87.0, 85.0, 102.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 154.0, 137.0, 139.0, 170.0, 159.0, 161.0, 147.0, 185.0, 142.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 167.0, 166.0, 206.0, 226.0, 183.0, 220.0, 164.0, 215.0], "value": 187.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 75.0, 82.0, 89.0, 68.0, 68.0, 84.0, 86.0, 90.0], "value": 77.9, "unit": "score"}]}]} 10:19:45 INFO - Return code: 0 10:19:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:19:45 ERROR - # TBPL SUCCESS # 10:19:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:45 INFO - 2017-01-13 10:19:45.920 screenresolution[1654:10465] starting screenresolution argv=screenresolution get 10:19:45 INFO - 2017-01-13 10:19:45.923 screenresolution[1654:10465] Display 0: 1600x1200x32@60 10:19:45 INFO - 2017-01-13 10:19:45.929 screenresolution[1655:10468] starting screenresolution argv=screenresolution list 10:19:45 INFO - Available Modes on Display 0 10:19:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:46 INFO - Intel Iris: 10:19:46 INFO - Chipset Model: Intel Iris 10:19:46 INFO - Type: GPU 10:19:46 INFO - Bus: Built-In 10:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:46 INFO - Vendor: Intel (0x8086) 10:19:46 INFO - Device ID: 0x0a2e 10:19:46 INFO - Revision ID: 0x0009 10:19:46 INFO - Displays: 10:19:46 INFO - Display: 10:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:46 INFO - Display Serial Number: 1600x1200 60 10:19:46 INFO - Main Display: Yes 10:19:46 INFO - Mirror: Off 10:19:46 INFO - Online: Yes 10:19:46 INFO - Rotation: Supported 10:19:46 INFO - Return code: 0 10:19:46 INFO - Running post-action listener: _resource_record_post_action 10:19:46 INFO - [mozharness: 2017-01-13 18:19:46.172627Z] Finished run-tests step (success) 10:19:46 INFO - Running post-run listener: _resource_record_post_run 10:19:46 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 37837312; Write bytes: 661029888; Read time: 398; Write time: 2145 10:19:46 INFO - TinderboxPrint: CPU usage
54.6% 10:19:46 INFO - TinderboxPrint: I/O read bytes / time
37,837,312 / 398 10:19:46 INFO - TinderboxPrint: I/O write bytes / time
661,029,888 / 2,145 10:19:46 INFO - TinderboxPrint: CPU idle
2,195.7 (45.4%) 10:19:46 INFO - TinderboxPrint: CPU system
984.4 (20.4%) 10:19:46 INFO - TinderboxPrint: CPU user
1,653.6 (34.2%) 10:19:46 INFO - TinderboxPrint: Swap in / out
387,256,320 / 0 10:19:46 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346477056; Write bytes: 347477504; Read time: 26848; Write time: 815 10:19:46 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35752448; Write bytes: 306462208; Read time: 373; Write time: 1302 10:19:46 INFO - Running post-run listener: _upload_blobber_files 10:19:46 INFO - Blob upload gear active. 10:19:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:46 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 10:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:19:47 INFO - (blobuploader) - INFO - Open directory for files ... 10:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:19:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:48 INFO - (blobuploader) - INFO - Done attempting. 10:19:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:19:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:49 INFO - (blobuploader) - INFO - Done attempting. 10:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:19:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:52 INFO - (blobuploader) - INFO - Done attempting. 10:19:52 INFO - (blobuploader) - INFO - Iteration through files over. 10:19:52 INFO - Return code: 0 10:19:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:19:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:19:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ff3c6b82c3cf82a9a195da18de83337da2eb9ad351611926fe8bed416e75d542ec70be449439d375f42edffd4c52e335c70ebed313775df715cc56a93687d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a5a6e9233a929a28625350c8b3950ecea74a052a524a9eac1e8e15ae7603285be6f139477425a4df834d6e5ec015da5f146c5cbf3793ef188c2c1bc2c75b2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c22b53616af221bf31f23a6a37cca3fadc48b29eeb3b4a362c6ac690694cf3a31c91db475fc5f852789f4bd3f4fb2d9123243254f31480ec37bde86f3e68fb"} 10:19:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:19:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:19:52 INFO - Contents: 10:19:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ff3c6b82c3cf82a9a195da18de83337da2eb9ad351611926fe8bed416e75d542ec70be449439d375f42edffd4c52e335c70ebed313775df715cc56a93687d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a5a6e9233a929a28625350c8b3950ecea74a052a524a9eac1e8e15ae7603285be6f139477425a4df834d6e5ec015da5f146c5cbf3793ef188c2c1bc2c75b2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c22b53616af221bf31f23a6a37cca3fadc48b29eeb3b4a362c6ac690694cf3a31c91db475fc5f852789f4bd3f4fb2d9123243254f31480ec37bde86f3e68fb"} 10:19:52 INFO - Running post-run listener: copy_logs_to_upload_dir 10:19:52 INFO - Copying logs to upload dir... 10:19:52 INFO - mkdir: /builds/slave/test/build/upload/logs 10:19:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.171573 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-13 10:19:52.492458) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:19:52.497004) ========= 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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ff3c6b82c3cf82a9a195da18de83337da2eb9ad351611926fe8bed416e75d542ec70be449439d375f42edffd4c52e335c70ebed313775df715cc56a93687d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a5a6e9233a929a28625350c8b3950ecea74a052a524a9eac1e8e15ae7603285be6f139477425a4df834d6e5ec015da5f146c5cbf3793ef188c2c1bc2c75b2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c22b53616af221bf31f23a6a37cca3fadc48b29eeb3b4a362c6ac690694cf3a31c91db475fc5f852789f4bd3f4fb2d9123243254f31480ec37bde86f3e68fb"} build_url:https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011113 build_url: 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ff3c6b82c3cf82a9a195da18de83337da2eb9ad351611926fe8bed416e75d542ec70be449439d375f42edffd4c52e335c70ebed313775df715cc56a93687d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a5a6e9233a929a28625350c8b3950ecea74a052a524a9eac1e8e15ae7603285be6f139477425a4df834d6e5ec015da5f146c5cbf3793ef188c2c1bc2c75b2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c22b53616af221bf31f23a6a37cca3fadc48b29eeb3b4a362c6ac690694cf3a31c91db475fc5f852789f4bd3f4fb2d9123243254f31480ec37bde86f3e68fb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:19:52.540508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:19:52.541290) ========= 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.IMEu3XQH0C/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.W9BzQaeR0e/Listeners TMPDIR=/var/folders/11/9f8m5vpx6cs7tqgxgrkjczwm00000w/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.004991 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:19:52.589849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:19:52.590195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:19:52.590532) ========= ========= Total master_lag: 0.19 =========