builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0146 starttime: 1485311425.31 results: success (0) buildid: 20170124163348 builduid: a6903288ef854d368d80913c985784aa revision: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.306791) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.307281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.307556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.334077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.334435) ========= 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.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-24 18:30:25-- 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% 39.2M=0s 2017-01-24 18:30:25 (39.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150607 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.509349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.509670) ========= 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.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026548 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:25.558515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 18:30:25.558873) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3861c5f62b183a33e3b70d8321b48fdaac05bec1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3861c5f62b183a33e3b70d8321b48fdaac05bec1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-24 18:30:25,661 truncating revision to first 12 chars 2017-01-24 18:30:25,661 Setting DEBUG logging. 2017-01-24 18:30:25,661 attempt 1/10 2017-01-24 18:30:25,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3861c5f62b18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-24 18:30:26,392 unpacking tar archive at: mozilla-beta-3861c5f62b18/testing/mozharness/ program finished with exit code 0 elapsedTime=0.989919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 18:30:26.572314) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:26.572631) ========= script_repo_revision: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:26.573073) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:26.573335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 18:30:26.593257) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-24 18:30:26.593576) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:30:26 INFO - MultiFileLogger online at 20170124 18:30:26 in /builds/slave/test 18:30:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:30:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:30:26 INFO - {'append_to_log': False, 18:30:26 INFO - 'base_work_dir': '/builds/slave/test', 18:30:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:30:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:30:26 INFO - 'branch': 'Mozilla-Beta', 18:30:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:30:26 INFO - 'config_files': ('talos/mac_config.py',), 18:30:26 INFO - 'default_actions': ('clobber', 18:30:26 INFO - 'read-buildbot-config', 18:30:26 INFO - 'download-and-extract', 18:30:26 INFO - 'populate-webroot', 18:30:26 INFO - 'create-virtualenv', 18:30:26 INFO - 'install', 18:30:26 INFO - 'run-tests'), 18:30:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:30:26 INFO - 'download_minidump_stackwalk': True, 18:30:26 INFO - 'download_symbols': 'ondemand', 18:30:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:30:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:30:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:30:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:30:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:30:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:30:26 INFO - 'installer_path': 'installer.exe', 18:30:26 INFO - 'log_level': 'info', 18:30:26 INFO - 'log_name': 'talos', 18:30:26 INFO - 'log_to_console': True, 18:30:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:30:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:30:26 INFO - 'opt_config_files': (), 18:30:26 INFO - 'pip_index': False, 18:30:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:26 INFO - 'cmd': ('bash', 18:30:26 INFO - '-c', 18:30:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:26 INFO - 'enabled': True, 18:30:26 INFO - 'halt_on_failure': False, 18:30:26 INFO - 'name': 'check_screen_resolution'},), 18:30:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:26 INFO - 'cmd': ('bash', 18:30:26 INFO - '-c', 18:30:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:26 INFO - 'enabled': True, 18:30:26 INFO - 'halt_on_failure': False, 18:30:26 INFO - 'name': 'check_screen_resolution'},), 18:30:26 INFO - 'run_cmd_checks_enabled': True, 18:30:26 INFO - 'sps_profile': False, 18:30:26 INFO - 'sps_profile_interval': 0, 18:30:26 INFO - 'suite': 'dromaeojs-e10s', 18:30:26 INFO - 'system_bits': '32', 18:30:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:30:26 INFO - 'title': 't-yosemite-r7-0146', 18:30:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:30:26 INFO - 'use_talos_json': True, 18:30:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:30:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:30:26 INFO - 'work_dir': 'build'} 18:30:26 INFO - [mozharness: 2017-01-25 02:30:26.808351Z] Running clobber step. 18:30:26 INFO - Running pre-action listener: _resource_record_pre_action 18:30:26 INFO - Running main action method: clobber 18:30:26 INFO - rmtree: /builds/slave/test/build 18:30:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:30:29 INFO - Running post-action listener: _resource_record_post_action 18:30:29 INFO - [mozharness: 2017-01-25 02:30:29.326522Z] Finished clobber step (success) 18:30:29 INFO - [mozharness: 2017-01-25 02:30:29.326680Z] Running read-buildbot-config step. 18:30:29 INFO - Running pre-action listener: _resource_record_pre_action 18:30:29 INFO - Running main action method: read_buildbot_config 18:30:29 INFO - Using buildbot properties: 18:30:29 INFO - { 18:30:29 INFO - "project": "", 18:30:29 INFO - "product": "firefox", 18:30:29 INFO - "script_repo_revision": "production", 18:30:29 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 18:30:29 INFO - "repository": "", 18:30:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 18:30:29 INFO - "buildid": "20170124163348", 18:30:29 INFO - "pgo_build": "False", 18:30:29 INFO - "basedir": "/builds/slave/test", 18:30:29 INFO - "buildnumber": 12, 18:30:29 INFO - "slavename": "t-yosemite-r7-0146", 18:30:29 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:30:29 INFO - "platform": "yosemite_r7", 18:30:29 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:30:29 INFO - "branch": "mozilla-beta", 18:30:29 INFO - "repo_path": "releases/mozilla-beta", 18:30:29 INFO - "revision": "3861c5f62b183a33e3b70d8321b48fdaac05bec1", 18:30:29 INFO - "stage_platform": "macosx64", 18:30:29 INFO - "builduid": "a6903288ef854d368d80913c985784aa", 18:30:29 INFO - "slavebuilddir": "test" 18:30:29 INFO - } 18:30:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 18:30:29 INFO - Running post-action listener: _resource_record_post_action 18:30:29 INFO - [mozharness: 2017-01-25 02:30:29.330037Z] Finished read-buildbot-config step (success) 18:30:29 INFO - [mozharness: 2017-01-25 02:30:29.330158Z] Running download-and-extract step. 18:30:29 INFO - Running pre-action listener: _resource_record_pre_action 18:30:29 INFO - Running main action method: download_and_extract 18:30:29 INFO - mkdir: /builds/slave/test/build/tests 18:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:29 INFO - trying https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 18:30:29 INFO - Downloading https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 18:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 18:30:30 INFO - Downloaded 1165 bytes. 18:30:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 18:30:30 INFO - Using the following test package requirements: 18:30:30 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 18:30:30 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 18:30:30 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 18:30:30 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:30:30 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 18:30:30 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 18:30:30 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 18:30:30 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 18:30:30 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 18:30:30 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:30:30 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 18:30:30 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 18:30:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 18:30:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 18:30:30 INFO - Fetch https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 18:30:30 INFO - Http code: 200 18:30:30 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 18:30:30 INFO - x-amz-cf-id: XhGcLCzOkVCXBkgRD6xWx4Vx6n1CFStpeS5XNs405h-MjXjDRUvleg== 18:30:30 INFO - x-amz-version-id: c0lg0GbUX6PdmPditNTwNcZO62Vj0eRg 18:30:31 INFO - Expected file size: 16086011 18:30:31 INFO - Obtained file size: 16086011 18:30:37 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 18:30:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 18:30:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 18:30:37 INFO - Fetch https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 18:30:38 INFO - Http code: 200 18:30:38 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 18:30:38 INFO - x-amz-cf-id: h9jrKDG8vMwe_RUdgQ50W3xG1RsThvhWmwpFSTZPj6entSuLESS4LQ== 18:30:38 INFO - x-amz-version-id: c0lg0GbUX6PdmPditNTwNcZO62Vj0eRg 18:30:38 INFO - Expected file size: 16086011 18:30:38 INFO - Obtained file size: 16086011 18:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 18:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 18:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 18:30:45 INFO - Http code: 200 18:30:45 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 18:30:45 INFO - x-amz-cf-id: 2O-Xvumb3U9o4IqpWfrWE9nJEs1FZUSGnd5oShbOBN-JYs2zobqS9w== 18:30:45 INFO - x-amz-version-id: WrXZiBURnsQPPq8YaPn.TeZvkItofbE6 18:30:46 INFO - Expected file size: 14402992 18:30:46 INFO - Obtained file size: 14402992 18:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:46 INFO - trying https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 18:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 18:30:48 INFO - Downloaded 82120999 bytes. 18:30:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:30:48 INFO - mkdir: /builds/slave/test/properties 18:30:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:48 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:48 INFO - Contents: 18:30:48 INFO - build_url:https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:30:49 INFO - Running post-action listener: _resource_record_post_action 18:30:49 INFO - Running post-action listener: set_extra_try_arguments 18:30:49 INFO - [mozharness: 2017-01-25 02:30:49.252520Z] Finished download-and-extract step (success) 18:30:49 INFO - [mozharness: 2017-01-25 02:30:49.252668Z] Running populate-webroot step. 18:30:49 INFO - Running pre-action listener: _resource_record_pre_action 18:30:49 INFO - Running main action method: populate_webroot 18:30:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:49 INFO - '--tppagecycles', 18:30:49 INFO - '7'], 18:30:49 INFO - 'tests': ['tsvgm']}}, 18:30:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['damp', 'tps']}, 18:30:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'tests': ['damp', 'tps']}, 18:30:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['dromaeo_dom']}, 18:30:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['basic_compositor_video']}, 18:30:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:30:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['a11yr', 18:30:49 INFO - 'ts_paint', 18:30:49 INFO - 'tpaint', 18:30:49 INFO - 'sessionrestore', 18:30:49 INFO - 'sessionrestore_no_auto_restore', 18:30:49 INFO - 'tabpaint']}, 18:30:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['tsvgx', 18:30:49 INFO - 'tsvgr_opacity', 18:30:49 INFO - 'tart', 18:30:49 INFO - 'tscrollx', 18:30:49 INFO - 'cart']}, 18:30:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:49 INFO - 'tsvgr_opacity', 18:30:49 INFO - 'tart', 18:30:49 INFO - 'tscrollx', 18:30:49 INFO - 'cart']}, 18:30:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'talos_options': ['--disable-e10s'], 18:30:49 INFO - 'tests': ['tp5o']}, 18:30:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'tests': ['tp5o']}, 18:30:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'talos_options': ['--disable-e10s', 18:30:49 INFO - '--xperf_path', 18:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:49 INFO - 'tests': ['tp5n']}, 18:30:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:49 INFO - 'talos_options': ['--xperf_path', 18:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:49 INFO - 'tests': ['tp5n']}}, 18:30:49 INFO - 'talos.zip': {'path': '', 18:30:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:49 INFO - Running post-action listener: _resource_record_post_action 18:30:49 INFO - [mozharness: 2017-01-25 02:30:49.271605Z] Finished populate-webroot step (success) 18:30:49 INFO - [mozharness: 2017-01-25 02:30:49.271708Z] Running create-virtualenv step. 18:30:49 INFO - Running pre-action listener: _resource_record_pre_action 18:30:49 INFO - Running main action method: create_virtualenv 18:30:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:30:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:49 INFO - Using real prefix '/tools/python27' 18:30:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:52 INFO - Installing pip.................done. 18:30:52 INFO - Return code: 0 18:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:30:53 INFO - Reading from file tmpfile_stdout 18:30:53 INFO - Output received: 18:30:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:30:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:53 INFO - 'HOME': '/Users/cltbld', 18:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:53 INFO - 'LOGNAME': 'cltbld', 18:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:53 INFO - 'MOZ_NO_REMOTE': '1', 18:30:53 INFO - 'NO_EM_RESTART': '1', 18:30:53 INFO - 'PAGER': '/bin/cat', 18:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:53 INFO - 'PWD': '/builds/slave/test', 18:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:53 INFO - 'SHELL': '/bin/bash', 18:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:30:53 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:53 INFO - 'USER': 'cltbld', 18:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:53 INFO - 'XPC_FLAGS': '0x0', 18:30:53 INFO - 'XPC_SERVICE_NAME': '0', 18:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:30:53 INFO - Cleaning up... 18:30:53 INFO - Return code: 0 18:30:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:53 INFO - 'HOME': '/Users/cltbld', 18:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:53 INFO - 'LOGNAME': 'cltbld', 18:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:53 INFO - 'MOZ_NO_REMOTE': '1', 18:30:53 INFO - 'NO_EM_RESTART': '1', 18:30:53 INFO - 'PAGER': '/bin/cat', 18:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:53 INFO - 'PWD': '/builds/slave/test', 18:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:53 INFO - 'SHELL': '/bin/bash', 18:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:30:53 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:53 INFO - 'USER': 'cltbld', 18:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:53 INFO - 'XPC_FLAGS': '0x0', 18:30:53 INFO - 'XPC_SERVICE_NAME': '0', 18:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:53 INFO - Downloading/unpacking psutil>=3.1.1 18:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:57 INFO - Installing collected packages: psutil 18:30:57 INFO - Running setup.py install for psutil 18:30:57 INFO - building 'psutil._psutil_osx' extension 18:30:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:30:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:30:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:30:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:30:58 INFO - building 'psutil._psutil_posix' extension 18:30:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:30:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:58 INFO - ^ 18:30:58 INFO - 1 warning generated. 18:30:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:58 INFO - Successfully installed psutil 18:30:58 INFO - Cleaning up... 18:30:58 INFO - Return code: 0 18:30:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:58 INFO - 'HOME': '/Users/cltbld', 18:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:58 INFO - 'LOGNAME': 'cltbld', 18:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:58 INFO - 'MOZ_NO_REMOTE': '1', 18:30:58 INFO - 'NO_EM_RESTART': '1', 18:30:58 INFO - 'PAGER': '/bin/cat', 18:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:58 INFO - 'PWD': '/builds/slave/test', 18:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:58 INFO - 'SHELL': '/bin/bash', 18:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:30:58 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:58 INFO - 'USER': 'cltbld', 18:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:58 INFO - 'XPC_FLAGS': '0x0', 18:30:58 INFO - 'XPC_SERVICE_NAME': '0', 18:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:31:01 INFO - Installing collected packages: mozsystemmonitor 18:31:01 INFO - Running setup.py install for mozsystemmonitor 18:31:01 INFO - Successfully installed mozsystemmonitor 18:31:01 INFO - Cleaning up... 18:31:01 INFO - Return code: 0 18:31:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:01 INFO - 'HOME': '/Users/cltbld', 18:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:01 INFO - 'LOGNAME': 'cltbld', 18:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:01 INFO - 'MOZ_NO_REMOTE': '1', 18:31:01 INFO - 'NO_EM_RESTART': '1', 18:31:01 INFO - 'PAGER': '/bin/cat', 18:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:01 INFO - 'PWD': '/builds/slave/test', 18:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:01 INFO - 'SHELL': '/bin/bash', 18:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:01 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:01 INFO - 'USER': 'cltbld', 18:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:01 INFO - 'XPC_FLAGS': '0x0', 18:31:01 INFO - 'XPC_SERVICE_NAME': '0', 18:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:04 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:05 INFO - warning: no files found matching '*.txt' 18:31:05 INFO - no previously-included directories found matching 'build' 18:31:05 INFO - no previously-included directories found matching 'dist' 18:31:05 INFO - no previously-included directories found matching '.git*' 18:31:05 INFO - Installing collected packages: jsonschema, functools32 18:31:05 INFO - Running setup.py install for functools32 18:31:05 INFO - warning: no files found matching '*.txt' 18:31:05 INFO - no previously-included directories found matching 'build' 18:31:05 INFO - no previously-included directories found matching 'dist' 18:31:05 INFO - no previously-included directories found matching '.git*' 18:31:05 INFO - Successfully installed jsonschema functools32 18:31:05 INFO - Cleaning up... 18:31:05 INFO - Return code: 0 18:31:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:05 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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:05 INFO - 'HOME': '/Users/cltbld', 18:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:05 INFO - 'LOGNAME': 'cltbld', 18:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:05 INFO - 'MOZ_NO_REMOTE': '1', 18:31:05 INFO - 'NO_EM_RESTART': '1', 18:31:05 INFO - 'PAGER': '/bin/cat', 18:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:05 INFO - 'PWD': '/builds/slave/test', 18:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:05 INFO - 'SHELL': '/bin/bash', 18:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:05 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:05 INFO - 'USER': 'cltbld', 18:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:05 INFO - 'XPC_FLAGS': '0x0', 18:31:05 INFO - 'XPC_SERVICE_NAME': '0', 18:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:31:05 INFO - Cleaning up... 18:31:05 INFO - Return code: 0 18:31:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:05 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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:05 INFO - 'HOME': '/Users/cltbld', 18:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:05 INFO - 'LOGNAME': 'cltbld', 18:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:05 INFO - 'MOZ_NO_REMOTE': '1', 18:31:05 INFO - 'NO_EM_RESTART': '1', 18:31:05 INFO - 'PAGER': '/bin/cat', 18:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:05 INFO - 'PWD': '/builds/slave/test', 18:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:05 INFO - 'SHELL': '/bin/bash', 18:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:05 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:05 INFO - 'USER': 'cltbld', 18:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:05 INFO - 'XPC_FLAGS': '0x0', 18:31:05 INFO - 'XPC_SERVICE_NAME': '0', 18:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:09 INFO - Downloading docopt-0.6.1.tar.gz 18:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:09 INFO - Running setup.py install for blobuploader 18:31:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:09 INFO - Running setup.py install for requests 18:31:10 INFO - Running setup.py install for docopt 18:31:10 INFO - Successfully installed blobuploader requests docopt 18:31:10 INFO - Cleaning up... 18:31:10 INFO - Return code: 0 18:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:10 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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:10 INFO - 'HOME': '/Users/cltbld', 18:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:10 INFO - 'LOGNAME': 'cltbld', 18:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:10 INFO - 'MOZ_NO_REMOTE': '1', 18:31:10 INFO - 'NO_EM_RESTART': '1', 18:31:10 INFO - 'PAGER': '/bin/cat', 18:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:10 INFO - 'PWD': '/builds/slave/test', 18:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:10 INFO - 'SHELL': '/bin/bash', 18:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:10 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:10 INFO - 'USER': 'cltbld', 18:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:10 INFO - 'XPC_FLAGS': '0x0', 18:31:10 INFO - 'XPC_SERVICE_NAME': '0', 18:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:10 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Tpwyki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-JXP72R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-JJ7wPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-XVyQPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-nFMNgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-8d2zdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-NhvPWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ihKP1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-QwnK2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-DqTz3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:11 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-5_DQQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-GRZcA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-FQl4VW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-k72zy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Zor5CZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-4oyh2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:12 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-7VZdMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:12 INFO - Running setup.py install for manifestparser 18:31:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:12 INFO - Running setup.py install for mozcrash 18:31:13 INFO - Running setup.py install for mozdebug 18:31:13 INFO - Running setup.py install for mozdevice 18:31:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Running setup.py install for mozfile 18:31:13 INFO - Running setup.py install for mozhttpd 18:31:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Running setup.py install for mozinfo 18:31:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Running setup.py install for mozInstall 18:31:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:13 INFO - Running setup.py install for mozleak 18:31:14 INFO - Running setup.py install for mozlog 18:31:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Running setup.py install for moznetwork 18:31:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Running setup.py install for mozprocess 18:31:14 INFO - Running setup.py install for mozprofile 18:31:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Running setup.py install for mozrunner 18:31:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:14 INFO - Running setup.py install for mozscreenshot 18:31:15 INFO - Running setup.py install for moztest 18:31:15 INFO - Running setup.py install for mozversion 18:31:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:15 INFO - Cleaning up... 18:31:15 INFO - Return code: 0 18:31:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:15 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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:15 INFO - 'HOME': '/Users/cltbld', 18:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:15 INFO - 'LOGNAME': 'cltbld', 18:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:15 INFO - 'MOZ_NO_REMOTE': '1', 18:31:15 INFO - 'NO_EM_RESTART': '1', 18:31:15 INFO - 'PAGER': '/bin/cat', 18:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:15 INFO - 'PWD': '/builds/slave/test', 18:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:15 INFO - 'SHELL': '/bin/bash', 18:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:15 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:15 INFO - 'USER': 'cltbld', 18:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:15 INFO - 'XPC_FLAGS': '0x0', 18:31:15 INFO - 'XPC_SERVICE_NAME': '0', 18:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:15 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-BkGI8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:15 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-6WxyTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:15 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-HelgPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:15 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Ev4kU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-XE4rff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-8sEZ2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-CY8oKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-TtgfEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-2TWe8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-xjhhLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-74tb2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qKf3eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-lcl3sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:16 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qiQlsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qGQLOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-QZI2OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-wTT5em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:19 INFO - Downloading blessings-1.6.tar.gz 18:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:20 INFO - Installing collected packages: blessings 18:31:20 INFO - Running setup.py install for blessings 18:31:20 INFO - Successfully installed blessings 18:31:20 INFO - Cleaning up... 18:31:20 INFO - Return code: 0 18:31:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:20 INFO - Reading from file tmpfile_stdout 18:31:20 INFO - Current package versions: 18:31:20 INFO - blessings == 1.6 18:31:20 INFO - blobuploader == 1.2.4 18:31:20 INFO - docopt == 0.6.1 18:31:20 INFO - functools32 == 3.2.3-2 18:31:20 INFO - jsonschema == 2.5.1 18:31:20 INFO - manifestparser == 1.1 18:31:20 INFO - mozInstall == 1.12 18:31:20 INFO - mozcrash == 1.0 18:31:20 INFO - mozdebug == 0.1 18:31:20 INFO - mozdevice == 0.48 18:31:20 INFO - mozfile == 1.2 18:31:20 INFO - mozhttpd == 0.7 18:31:20 INFO - mozinfo == 0.9 18:31:20 INFO - mozleak == 0.1 18:31:20 INFO - mozlog == 3.4 18:31:20 INFO - moznetwork == 0.27 18:31:20 INFO - mozprocess == 0.23 18:31:20 INFO - mozprofile == 0.28 18:31:20 INFO - mozrunner == 6.13 18:31:20 INFO - mozscreenshot == 0.1 18:31:20 INFO - mozsystemmonitor == 0.3 18:31:20 INFO - moztest == 0.8 18:31:20 INFO - mozversion == 1.4 18:31:20 INFO - psutil == 3.1.1 18:31:20 INFO - requests == 1.2.3 18:31:20 INFO - wsgiref == 0.1.2 18:31:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:20 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 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:20 INFO - 'HOME': '/Users/cltbld', 18:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:20 INFO - 'LOGNAME': 'cltbld', 18:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:20 INFO - 'MOZ_NO_REMOTE': '1', 18:31:20 INFO - 'NO_EM_RESTART': '1', 18:31:20 INFO - 'PAGER': '/bin/cat', 18:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:20 INFO - 'PWD': '/builds/slave/test', 18:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:20 INFO - 'SHELL': '/bin/bash', 18:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:20 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:20 INFO - 'USER': 'cltbld', 18:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:20 INFO - 'XPC_FLAGS': '0x0', 18:31:20 INFO - 'XPC_SERVICE_NAME': '0', 18:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:31:20 INFO - Cleaning up... 18:31:20 INFO - Return code: 0 18:31:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e33b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86895a73f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, '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.9ACDna87sY/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.H7OztSppXh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:31: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', 'jsonschema'] in /builds/slave/test/build 18:31: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 jsonschema 18:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:20 INFO - 'HOME': '/Users/cltbld', 18:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:20 INFO - 'LOGNAME': 'cltbld', 18:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:20 INFO - 'MOZ_NO_REMOTE': '1', 18:31:20 INFO - 'NO_EM_RESTART': '1', 18:31:20 INFO - 'PAGER': '/bin/cat', 18:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:20 INFO - 'PWD': '/builds/slave/test', 18:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:20 INFO - 'SHELL': '/bin/bash', 18:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:20 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:20 INFO - 'USER': 'cltbld', 18:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:20 INFO - 'XPC_FLAGS': '0x0', 18:31:20 INFO - 'XPC_SERVICE_NAME': '0', 18:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:31:21 INFO - Cleaning up... 18:31:21 INFO - Return code: 0 18:31:21 INFO - Running post-action listener: _resource_record_post_action 18:31:21 INFO - Running post-action listener: _start_resource_monitoring 18:31:21 INFO - Starting resource monitoring. 18:31:21 INFO - [mozharness: 2017-01-25 02:31:21.094017Z] Finished create-virtualenv step (success) 18:31:21 INFO - [mozharness: 2017-01-25 02:31:21.094956Z] Running install step. 18:31:21 INFO - Running pre-action listener: _resource_record_pre_action 18:31:21 INFO - Running main action method: install 18:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:21 INFO - Reading from file tmpfile_stdout 18:31:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:21 INFO - Reading from file tmpfile_stdout 18:31:21 INFO - Output received: 18:31:21 INFO - Usage: mozinstall [options] installer 18:31:21 INFO - Options: 18:31:21 INFO - -h, --help show this help message and exit 18:31:21 INFO - -d DEST, --destination=DEST 18:31:21 INFO - Directory to install application into. [default: 18:31:21 INFO - "/builds/slave/test"] 18:31:21 INFO - --app=APP Application being installed. [default: firefox] 18:31:21 INFO - mkdir: /builds/slave/test/build/application 18:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 18:31:42 INFO - Reading from file tmpfile_stdout 18:31:42 INFO - Output received: 18:31:42 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:31:42 INFO - Running post-action listener: _resource_record_post_action 18:31:42 INFO - [mozharness: 2017-01-25 02:31:42.823617Z] Finished install step (success) 18:31:42 INFO - [mozharness: 2017-01-25 02:31:42.823796Z] Running run-tests step. 18:31:42 INFO - Running pre-action listener: _resource_record_pre_action 18:31:42 INFO - Running main action method: run_tests 18:31:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:42 INFO - 2017-01-24 18:31:42.838 screenresolution[1619:7274] starting screenresolution argv=screenresolution get 18:31:42 INFO - 2017-01-24 18:31:42.840 screenresolution[1619:7274] Display 0: 1600x1200x32@60 18:31:42 INFO - 2017-01-24 18:31:42.846 screenresolution[1620:7277] starting screenresolution argv=screenresolution list 18:31:42 INFO - Available Modes on Display 0 18:31:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:43 INFO - Intel Iris: 18:31:43 INFO - Chipset Model: Intel Iris 18:31:43 INFO - Type: GPU 18:31:43 INFO - Bus: Built-In 18:31:43 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:43 INFO - Vendor: Intel (0x8086) 18:31:43 INFO - Device ID: 0x0a2e 18:31:43 INFO - Revision ID: 0x0009 18:31:43 INFO - Displays: 18:31:43 INFO - Display: 18:31:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:43 INFO - Display Serial Number: 1600x1200 60 18:31:43 INFO - Main Display: Yes 18:31:43 INFO - Mirror: Off 18:31:43 INFO - Online: Yes 18:31:43 INFO - Rotation: Supported 18:31:43 INFO - Return code: 0 18:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:43 INFO - Python 2.7.3 18:31:43 INFO - Return code: 0 18:31:43 INFO - grabbing minidump binary from tooltool 18:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:43 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 0x10e14fd30>, '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 0x10dfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e147b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:31:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:31:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:31:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:43 INFO - Return code: 0 18:31:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9ACDna87sY/Render', 18:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:43 INFO - 'HOME': '/Users/cltbld', 18:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:43 INFO - 'LOGNAME': 'cltbld', 18:31:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:43 INFO - 'MOZ_NO_REMOTE': '1', 18:31:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:43 INFO - 'NO_EM_RESTART': '1', 18:31:43 INFO - 'PAGER': '/bin/cat', 18:31:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:43 INFO - 'PWD': '/builds/slave/test', 18:31:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:43 INFO - 'SHELL': '/bin/bash', 18:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7OztSppXh/Listeners', 18:31:43 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 18:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:43 INFO - 'USER': 'cltbld', 18:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:43 INFO - 'XPC_FLAGS': '0x0', 18:31:43 INFO - 'XPC_SERVICE_NAME': '0', 18:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:31:43 INFO - mozversion application_buildid: 20170124163348 18:31:43 INFO - mozversion application_changeset: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 18:31:43 INFO - mozversion application_display_name: Firefox 18:31:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:43 INFO - mozversion application_name: Firefox 18:31:43 INFO - mozversion application_remotingname: firefox 18:31:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:31:43 INFO - mozversion application_vendor: Mozilla 18:31:43 INFO - mozversion application_version: 52.0 18:31:43 INFO - mozversion platform_buildid: 20170124163348 18:31:43 INFO - mozversion platform_changeset: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 18:31:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:31:43 INFO - mozversion platform_version: 52.0 18:31:43 INFO - using testdate: 1485311503 18:31:43 INFO - actual date: 1485311503 18:31:43 INFO - starting webserver on 'localhost:49240' 18:31:43 INFO - SUITE-START | Running 2 tests 18:31:43 INFO - TEST-START | dromaeo_css 18:31:43 INFO - operating with platform_type : mac_ 18:31:43 INFO - Initialising browser for dromaeo_css test... 18:31:43 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmp3rVFbo/profile http://localhost:49240/getInfo.html) 18:31:46 INFO - PROCESS | 1630 | 2017-01-24 18:31:46.408 plugin-container[1633:7445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 18:31:46 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:46 INFO - PROCESS | 1630 | 2017-01-24 18:31:46.409 plugin-container[1633:7445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 18:31:46 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:46 INFO - PROCESS | 1630 | 2017-01-24 18:31:46.409 plugin-container[1633:7445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:31:46 INFO - PROCESS | 1630 | __metrics Screen width/height:1600/1200 18:31:46 INFO - PROCESS | 1630 | colorDepth:24 18:31:46 INFO - PROCESS | 1630 | Browser inner width/height: 1024/689 18:31:46 INFO - PROCESS | 1630 | __metrics 18:31:46 ERROR - PROCESS | 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 18:31:47 INFO - TEST-INFO | 1630: exit 0 18:31:47 INFO - Browser initialized. 18:31:47 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:47 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmp3rVFbo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:49 INFO - PROCESS | 1634 | 2017-01-24 18:31:49.796 plugin-container[1635:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 18:31:49 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:49 INFO - PROCESS | 1634 | 2017-01-24 18:31:49.796 plugin-container[1635:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 18:31:49 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:49 INFO - PROCESS | 1634 | 2017-01-24 18:31:49.796 plugin-container[1635:7576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:31:59 INFO - PROCESS | 1634 | RSS: Main: 177381376 18:31:59 INFO - PROCESS | 1634 | 18:35:01 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 18:35:03 INFO - PROCESS | 1634 | RSS: Main: 182796288 18:35:03 INFO - PROCESS | 1634 | 18:38:05 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 18:38:06 INFO - PROCESS | 1634 | RSS: Main: 196272128 18:38:06 INFO - PROCESS | 1634 | 18:41:08 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 18:41:09 INFO - PROCESS | 1634 | RSS: Main: 197939200 18:41:09 INFO - PROCESS | 1634 | 18:44:10 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 18:44:11 INFO - PROCESS | 1634 | RSS: Main: 202207232 18:44:11 INFO - PROCESS | 1634 | 18:47:14 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 18:47:14 INFO - PROCESS | 1634 | RSS: Main: 203612160 18:47:14 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 18:50:17 INFO - PROCESS | 1634 | RSS: Main: 203837440 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | __start_tp_report 18:50:17 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 18:50:17 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 18:50:17 INFO - PROCESS | 1634 | |i|pagename|runs| 18:50:17 INFO - PROCESS | 1634 | |0;dojo.html;3412.7099196568292;3554.484599733039;3618.402963511019;3622.655847694469;3628.909277268068;5959.940400595992;5990.970045149779;5998.970005149979;6011;6019.428154325337;6008.909866351997;6083.148359229712;6085.8741132890445;6089.147519347295;6101.328853826087;5338.572914166867;5434.538064264542;5444.3194600674915;5457.754401051942;5527.170924361338;5318.228856815759;5447.782088716446;5507.752151153188;5534.916976245339;5572.498475137237;5341.679499230039;5349.545288650469;5497.725113744297;5505.366882808468;5507.118860982244;5460.96241714075;5471.945280547203;5487.368952570466;5517.200005999148;5582.972085139589;5328.626996110235;5429.239906413105;5448.700321482317;5453.127499600045;5476.3702174250075;5800.158976948361;6087.817365479043;6099.603525770811;6111.84720381994;6116.541259405562;5408.756605952742;6228.937710622881;6234.750609975596;6252.187215661935;6265.404786545271;5325.920111198335;6396.648184349859;6404.167458230447;6441.774537891151;6488.026795980593;6172.290186628506;6172.4136207060255;6176.289726681399;6179.721912513993;6183.907241391383;6515.902261466082;6528.510361722795;6533.901991470132;6605.1083103780375;6621.86756264872;6316.905246421307;6319.557630965788;6320.71556779946;6320.810375688737;6323.20959880015;6220.160278362363;6248.968755156195;6456.54804163704;6480.287168411471;6485.4811615070685;6244.875102497925;6313.273973493016;6323.336049714806;6372.171617689671;6394.584352017104;5269.631125821155;6349.746010159634;6350.983842585257;6392.169018027626;6394.5843520171975;5208.739563021833;5230.97384513075;5240.187770895517;5243.606729495227;5245.344331958505;3356;4029.2143032109043;4091.2226676931286;4528.705634133771;4612.492625811158;5971.206866438749;6219.40915613016;6224.253089629183;6227.252729672377;6229.688515574203;6407.231132264158;6456.386643268882;6487.545871789024;6492.805215843532;6510.218773747086;6090.847728806727;6096.603720758137;6127.433875357673;6139.676133582418;6147.692615369214;5647.745851436695;5685.630434021776;5702.971485142548;5740.741666624928;5775.075987841925;6637.701303441357;6646.468282537386;6671.299513551007;6680.966595166993;6687.1975362956755;6167.722452489738;6284.962981108066;6452.290248072614;6460.160179176865;6466.353364663496;6525.216973962965;6551.148350714377;6594.175728033996;6657.400833924777;6670.099536562696;6031.969840150947;6328.778492752732;6334.461570766352;6340.841478962879;6346.746130154742;6264.780732674334;6364.395382438661;6368.426841584094;6441.291457939717;6452.9677351612945;6211.316755156839;6219.751209951552;6258;6262.2172228471445;6321.557490975588;6263.467605253604;6275.560710750204;6293.716782744696;6293.96853015732;6295.842603934938;6109.358517355614;6141.508679305734;6235.158253635883;6265.843353916189;6321.241451025906;6175.44421002112;6183.536234782499;6191.597546159485;6237.126802247598;6260.123582698517;6242.563020588515;6259.467945224525;6267.811965641038;6288.276848162337;6289.874202516015 18:50:17 INFO - PROCESS | 1634 | |1;ext.html;473.68263263613375;487.3347880143605;494.5549005894694;523.5183631059425;603.7434090511531;40845;42911.57088429115;42934.92662683434;42946.14107717848;43365.34951975723;37857.62142378576;44723.77638111805;44727.77636111815;44811.551884481145;44838;9631.518424078786;9832.360896541702;9897.901020989806;9902.405855648649;9918.25613079022;8264.752057438256;8597.398182127254;9676.806463870718;9775;9785.363951343175;9720.951395243013;9740.610375584969;9756.658516951908;9825.459599722013;9827.803443931118;8098.838023239532;9760.365576237593;9805.264605154642;9809.852852207223;9817.90182098177;9738.367006144577;9763.170130538912;9769.218462523053;9843.277426450413;9858.260630452745;42472;42487.9378015549;42836.57163428357;43008.139837203395;43136.784316078534;3361.512580675813;3478.7043101336417;4024.6579040781276;4033.757974521538;4188.748675079505;30177.396452071054;30313.090607281596;30506.932257371107;31785.84107079473;32722.83638581792;3171.1279398165457;3287.621923478807;3330.317284956578;3356.3790698720813;3359.5128706337687;508657;510564;511830;513662;515044;63853;64469.35530644727;64476.67761661162;64725.70548589096;65090.67454662791;67686.9846952296;68498.3150168492;68692.656536718;68723;68883.31116688868;16479.92880462766;16766.993980361218;16829.242684079247;17222.741658875126;17250.913745431193;10516;10539.84190237147;10567;10570.260081794355;10628.096611788085;16506.42227522031;18168.339192081883;19682.212711491666;19931.302404416067;19966.70049949252;3292.226326813197;3395.3548825723033;3400.931981360359;8071.354291656771;8159.877601835978;984.3945973226557;1024.7130803375067;1047.6542740895486;1101.1938686001172;1123.3765260280459;27396.58905116425;29616.555751663742;29834.10497685073;30503.23741906426;30605.693943060283;857.7898414888392;1040.4641609571077;1108.2131686502673;1111.5998240633367;1168.6669299249672;15648.982816116915;15896.284667190004;15991.36034558628;16004;16142.581677038825;681604;682497;688293;688437;690292.5485372641;59082.70458647765;59650.40349596535;59762;59887.70056149778;60194.39805601975;1403.4245959156697;1408.2677007955806;1429.7426463236718;1432.0691550492056;1433.7921001454731;29910;30318.136047775275;30371.2407189822;30646.84676576603;31015.68984310128;53156;53340.46659533355;53355.7332213352;53451.46548534465;53666.46333536615;621339.8933005306;623077;624291.7570824233;625995;626053;27391.904323826824;27415.451690966467;27787.861060695377;27812.44375112527;28043.298917527223;45153.54846451625;46890.06219875652;47070;47383.28925066058;47840;39090.4136437948;39505.012374690865;39577.406338904366;41245.58754412417;41306.793466032475;39291.21417571575;39711;39713.60286397099;40214;40938;37181.07047323838;38742.418863717634;39013.609863900994;39023.024424389616;39235.60764392434;29051.564226536197;31218.531722023734;31661.8416907914;31709.5243571342;32037.67962320347;21516;26414.867925660248;26492.470150597266;26632;26723 18:50:17 INFO - PROCESS | 1634 | |2;jquery.html;381.8968878402831;385.22569635033574;389.1671822300277;396.77978721809393;414.3660199894161;16168.595785105348;20076.297329593468;20352.796472035276;21311.467213319655;21359.466013349655;20317.18731250748;20509.589808203826;20536.691949620767;20921.47696307593;21071.68392474114;5217.817376391837;5491.368492623339;5495.313085864267;5496.945030549703;5613.943860561403;5382.9730851345685;5529.225908372831;5570.972145139269;5593.608447408663;5611.971940140294;5131.692098474085;5499.642523235997;5570.359408668014;5629.183768353586;5630.071038278679;4452.666050046243;4928.926066109011;5252.107141785906;5561.082421400484;5620.156976453523;4164.126143235556;4782.641301902337;4866.732329721864;4959.900801983977;5056.26684130799;13600.931995340059;13830.723385532234;20694.793052069435;22995.655065174036;25939.74060259411;2308.0883051194683;2308.1575225042907;2441.7069951605745;2662.9747547194274;2750.7386798254133;9923.702288931343;10041.297109202358;10079.244056695778;10359.79280414395;10558.947205263925;1698.7282034874484;1802.6124383257531;1861.4508719927653;1889.6126294109674;1935.6225536020481;124119.3794031033;125510.37244813809;128236.3588182053;129259.35370323183;131044.34477827646;11536.307821530734;11814.586489472827;11910.106741994385;11995.340256285988;12134.878651213376;11817.409129543488;12495.875041249472;13394.732105357838;15194.392224310905;15246.923765381323;6701.765438209639;8299.377546684023;8420.78948026292;8461.196186362286;8522;8686.39195256326;8735.213830755136;8746.256568191648;8863.601137948808;8877.866831997526;4007.1184339445276;4078.163976384834;4093.5701751315682;4101.835926562963;4128.442660240889;1702.2425020865844;1978.9307374241832;2023.908924098419;2027.7668068172054;2029.2852539604012;832.9296853543168;843;856.6744637037885;876.7238319929329;1019.6584144311738;20258.69611955822;20472;20642.793572064387;20848.081268622405;21019.8949005254;766.3792328214164;928.3455164109287;940.9294302927308;1017.4963393120453;1026.625281772148;10544.683659490227;10696.465176741132;10761.354318740901;10774.46127693628;10809.891901080888;612845;614101;621423;629235;630268;89556.5522172385;109553.45223273992;132739;137304;140587.29706351404;992.8411454167443;1037.4034929915179;1046.2048842879315;1048.2138396202847;1049.1134990932649;18604.7209291858;18837.811621883604;19148.425547233604;19236.422907312804;19242.422727318204;56304.43695562992;56816;57366.7131664339;58814.11778823406;59076.70461647664;55566.72216638891;79494.205057951;83341;84556.73164902655;86978;6312.589681670676;8146.633401497065;8261.421700480909;8285.54429506383;8345.123762005145;12326.445309960895;12649.810252846031;12853.550125745554;12938.611841644766;13072.215667060007;8470.364722645703;11082.889171108509;11155.609553665907;11336.659900203007;11361.091112711129;10925.781484370427;10989.395583242998;11075.778484430106;11078.612248571262;11254;11121.83317250258;11254.099672026383;11294.491747871201;11400.543978240778;11640.88359116398;6804;8210.794730131794;8313.542755148525;8349;8509.446885952393;9048.638054477748;9226.815463690822;10009.199264059003;11442;11497.482613282255 18:50:17 INFO - PROCESS | 1634 | |3;mootools.html;1436.97844532332;1505.4580351073614;1719.845213930746;1838.6414649143424;1873.2319748902953;7679.539227646345;7934.563599002053;8139.674413023479;8591.570421478933;8612.827743445127;8086.67653293869;8376.120507346732;8394.790130246747;8503;8508.234258916696;2109.48317662173;2339.2046704120594;2379.6311571706415;2407.374082738492;2413.54142712884;2408.7109546854404;2427.405285704996;2434.926952191437;2449.5590793657134;2464.6303054541872;2375.287413775869;2387.3673476528734;2403.507281007398;2404.5671779079757;2409.481961378312;2354.82338824588;2372.608519594265;2377.8097752179783;2379.1910750344878;2379.4765151666606;2408.000679717915;2416.4804567018;2431.538007778516;2435.2937648082034;2439.341377827994;8426.368022398345;8502.702405415781;8551.786205344853;8567.228949394585;8582;5195.558377537914;5290.418054014055;5330.92003619946;5331.360236771573;5344.038073146832;3411.64177761334;3472.9305413891834;3587.318409502186;3609.9278014439565;3621.2938476996997;980.8283550378726;1015.3700520638672;1045.278757657214;1090.3948308688675;1111.0112000319723;10869.728256793564;10911.83632245517;10914.945425272823;10943.343399395982;10944.99999999992;4542.591166795004;4668.8132474701315;4746;4752.809887604457;4800.807967681254;9416.387934784218;9433;9470.715878523655;9494.050594940589;9503.57233924475;5253.632245742838;5256.474352564713;5257.316548848626;5292.814751483679;5301.5493683037375;4365.628921541641;4373.169097871394;4834.830780922651;4847.297141914473;4870.05034018363;3893.5717071122153;3903.394973779069;3979.8209080590864;3993.1813978134414;4030.3349947258375;1297.4874924404808;1305.830242068544;1306.6080175947368;1311.4491913396396;1318.8483324417623;792.9920700792918;837.3468694418364;875.6584931876573;932.8693982842408;938.394735395666;5843.626747714284;5894.557908156906;5940.108983652487;6029.668368239789;6143.600665956698;769.169297159078;937.4188003437912;956.4739393333639;963.8987906269881;1014.2494554030112;3440.6559344065395;3495.685388315064;3951.6245956634075;4003.499562554681;4063.2482990646827;9873.062059104373;9920.950395247977;10153.13698335635;10210.795784084277;10258.53836577341;9906.752331191778;10166.949165254275;10188.388696678223;10212.336198147097;10218.489075546193;7784.221577842283;7857.253560911704;7865.685372585032;7896.052473703192;8026.117127116013;5658.405867383865;5695.202671625892;5790.478856902899;5927.288725352985;5957.195778569838;9078.954605227196;9219.216366608645;9368.110029547182;9492.762680932756;9493.003234660537;8057.959710201411;9421.057894210524;9431.57557909882;9488.810223795348;9631.988641192855;5888.440598143169;6547.181602299713;6654.334566543307;7175.067241258812;7302.56184628924;7761.107472640719;7919.84160316802;7982.640781164745;7995.520268783892;8011.278984891388;7074.504784665024;7115.2528984455885;7291.125064992235;7311.707531698672;7315.561066336037;6809.89785153233;6945.166580010431;6972.860542789217;7065.399441047363;7081.964590177016;6700.363465470772;6700.43046341047;6740;6783.525153239226;6786.694598743167;4918.5573298403315;4927.162382394929;4932.8520144395725;4949.554540091409;4955.231939049453;4924.679895806761;4940.802367905244;4965.851024469127;5001.824936127218;5014.52362025607 18:50:17 INFO - PROCESS | 1634 | |4;prototype.html;311.21728851937365;317.1421305368977;323.3855674218983;324.4192894718454;324.8797944760439;5463.426340234278;5601.719914004304;5623.887522249553;5763.481286684198;5764.769409223632;5335.759890804913;5554.305711786027;5575.944240557593;5587.859169564001;5592.3009623797025;3552.5381700378916;4290.120525292323;4308.913821723564;4331.133773245348;4331.7184383015165;3657.5610926688832;4263.2965560682605;4297.269464191095;4346.608805207531;4355.237833379162;3491.476278558211;3939.9756063423574;4207.747535147885;4253.680973926952;4286.807093680776;3536.929261414783;4248.196190514246;4264.722793018444;4292.720973136736;4314.576189857371;3082.352705931757;3235.8867439639735;3740.17716102457;3748.194138260287;3767.6609105180664;4436.778161091933;5087.56755675768;5370.570354371641;5413.621046526745;5485.561155107582;3973.721839471238;4276.48682158143;4891.853244402673;4904.5340692634145;4934.55588996992;5676.460736230051;5880;5898.991514327681;5905.527557795366;5969.791057312973;3734.9813250933844;4045.696572757055;4096.73371230869;4108.630223279919;4119.629233368982;5364.356277246756;6209.285932117819;6226.571637061505;6238.438540531374;6274.172754619678;4124.195781822576;4157.08544120293;4162.91674166521;4176.937345939813;4199.4120823084795;3797.2785170817456;3869.787161706077;4163.708540402204;4185.853495127717;4185.874423767231;3976.8210430530694;4542.841575398253;4864.80540778372;4865.464798872121;4897.559219670246;5206.479352064839;5399.352077750695;5641.746121424546;5975.760969561169;5994.670293133832;1086.4947799273218;1098.901098901099;1319.459021801057;1335.8063080853221;1342.5166939901628;889.1686273334453;898.6899519665704;912.7124955638952;918.2837386838277;923.3951761596165;4263.147370525907;4599.655025873073;5201.427842937273;5301.734913254322;5307.920381194285;5250.200989718324;5739.2252045973255;5826.921155191299;5853.795117170964;5943.4547017775685;5331.5201631853315;5388.811391601275;5438.83683489496;5453;5610.5231055360755;2583.9870800646254;2793.8882444701985;3111.471049921519;3127.9687203128133;3130.827804470776;5045.772940217699;5242.423333433319;5843.795467158629;6055.909161362583;6261.592996455307;4946.455889852114;5525.977694126519;5986.635279064745;6013.51891848651;6019.789307374221;5061.291419201241;5106.642535022513;5197.740112994411;5870.569723006288;6135.570510064342;5433.42430695094;5628.634138780966;5790.420957904176;6000.859407009149;6033.155358249761;4818.879528011687;5333.013392522395;5398.406175320633;5636.802711905064;5678.488935995781;5043.478260869565;5900.701845593962;6061.635961974745;6171.241196259045;6183.258009038944;3894.727369084137;3959.44567760508;3991.5409727881665;4207.600277973587;4258.531561528292;5306.665067370344;6050.5764596479585;6100.572959892942;6104.328523862283;6166.691665416801;5815.680137592473;5994.362480508562;6005.558665920235;6043.244594425697;6070.666113363807;5083.694978301313;5283.837555737731;5638.182463542846;5998.790042348497;6048.12302216185;5395.406505284494;5515.834524964257;5535.861603459945;5658.839937812821;5891.528677705859;5118.72031992002;5849.415058494116;6006.549508786771;6067.87744267312;6085.817425477243;5324.414314425488;5520.966993025219;5633;5727.828165155052;5921.792737254175 18:50:17 INFO - PROCESS | 1634 | |5;yui.html;665.9667016649166;673.8214373191105;696.2237105627228;737.8931602596102;830.7715378270976;12680.429380677868;12830.588635250115;14624.70750584989;15192;15444.69110617787;13927.721445571107;13975.510857120003;14018.649533761662;14783.334749936252;15363.23183840809;828.2380210206594;850.1498501498502;858.8110615664543;870.3472395703222;873.1137895036543;816.6121092481084;829.5520418973747;857.5840717252128;858.6179150278098;861.4142383179435;860.1398601398602;864.2437866866492;882.858742601184;902.3548163063408;906.4515967839428;857.661223816592;873.4409977614364;888.7511496780912;900.3922352412096;906.0486489186394;652.0968458684708;656.957297775643;669.7019826177353;669.8712669152494;670.9899351509682;14637.780433293508;14902.478413255483;14959.878009149357;15045.774313385307;15178.468753593681;13621.182729036289;13709.040367174302;14096.788548171786;14159.78760318596;14237.145771253654;11542.538298468051;11588.188826782129;11942.522299108112;11956.461959211769;12048.216865903776;3691.7231207659265;3743.2139250757373;3751.9249615007548;3880.9521429213955;3901.5318161820765;92465.07534924633;93072;93146.60280095803;93856.53071734597;94930.5253473742;10345.327553708985;10728.839067413839;10792.838107428237;10865.782684346268;11073.390963496924;20549;20681.86249756278;20731.585368292548;20794;20859.957002149833;7897.921020789719;7904.604769761489;7952.840943181104;7960.164182760843;8092.797680057928;4425.778711064434;4431.800568974404;4547.249703798902;4568.862934111982;4589.35748995141;3446.276281980762;3502.7372947029075;3556.270964452281;3565.2156525564333;3571.7321200909514;637.8851806674751;642.0497663458108;646.2148489585177;650.1645385679379;658.4534836086037;607.6050567131311;676.671814170127;679.3919442099293;688.9311068893071;690.7555696503651;9130.845465313267;9268.72193834186;9494.810103797885;9541.809163816824;9636.46999415025;538.5556915544691;600.9519238460913;631.5673763472037;697.0554898113085;702.8875379939185;4636.837710680177;4690.718556886598;4719.1741445247635;4720.811167553328;4747.287906813936;75159.62420187864;92721.5363923176;114119.42940285246;116041;119874.40062799805;61788.382116179164;87852;94019.0598094024;95261.0473895266;97642;76841.61579192069;78488;78804.21195788083;80774;88692;8146.592670366576;9620.278479113955;9824.214062875095;9983.750406239611;10072.446015469219;47148;53707.462925370244;54235.91528169336;55945.720271398386;56394.7180264096;71284.64357678179;72693;74260;77312.29406970729;80308.59845700733;12390;13164.946804255827;13237.404316805574;13269;13316.600501984956;13987.650308742363;14614.707705846036;14791.260436978324;14928.02967807089;15488.5353439397;11675.708107297385;12625.053121015777;13666.52167174144;13875.236861972688;14080.295985200493;13551.457941682616;13685.384157713126;13757.656058598615;13862.4455021798;13937.810735653396;12832.486700531874;12957.611271661865;13496.797548036591;13522.661933451742;13645.385957631732;11150.330980140892;11419.14356423255;11530.76938461243;11818.645440636794;12026.75946481083;11508.88491115078;11563.6530904073;11638.672265546755;11665.416729163677;11777.646670599896 18:50:17 INFO - PROCESS | 1634 | __end_tp_report 18:50:17 INFO - PROCESS | 1634 | __start_cc_report 18:50:17 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,365 18:50:17 INFO - PROCESS | 1634 | __end_cc_report 18:50:17 INFO - PROCESS | 1634 | __startTimestamp1485312617523__endTimestamp 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | ------- Summary: start ------- 18:50:17 INFO - PROCESS | 1634 | Number of tests: 6 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5955.38 Median:6219.41 stddev:673.08 (10.8%) stddev-sans-first:644.91 18:50:17 INFO - PROCESS | 1634 | Values: 3412.7 3554.5 3618.4 3622.7 3628.9 5959.9 5991.0 5999.0 6011.0 6019.4 6008.9 6083.1 6085.9 6089.1 6101.3 5338.6 5434.5 5444.3 5457.8 5527.2 5318.2 5447.8 5507.8 5534.9 5572.5 5341.7 5349.5 5497.7 5505.4 5507.1 5461.0 5471.9 5487.4 5517.2 5583.0 5328.6 5429.2 5448.7 5453.1 5476.4 5800.2 6087.8 6099.6 6111.8 6116.5 5408.8 6228.9 6234.8 6252.2 6265.4 5325.9 6396.6 6404.2 6441.8 6488.0 6172.3 6172.4 6176.3 6179.7 6183.9 6515.9 6528.5 6533.9 6605.1 6621.9 6316.9 6319.6 6320.7 6320.8 6323.2 6220.2 6249.0 6456.5 6480.3 6485.5 6244.9 6313.3 6323.3 6372.2 6394.6 5269.6 6349.7 6351.0 6392.2 6394.6 5208.7 5231.0 5240.2 5243.6 5245.3 3356.0 4029.2 4091.2 4528.7 4612.5 5971.2 6219.4 6224.3 6227.3 6229.7 6407.2 6456.4 6487.5 6492.8 6510.2 6090.8 6096.6 6127.4 6139.7 6147.7 5647.7 5685.6 5703.0 5740.7 5775.1 6637.7 6646.5 6671.3 6681.0 6687.2 6167.7 6285.0 6452.3 6460.2 6466.4 6525.2 6551.1 6594.2 6657.4 6670.1 6032.0 6328.8 6334.5 6340.8 6346.7 6264.8 6364.4 6368.4 6441.3 6453.0 6211.3 6219.8 6258.0 6262.2 6321.6 6263.5 6275.6 6293.7 6294.0 6295.8 6109.4 6141.5 6235.2 6265.8 6321.2 6175.4 6183.5 6191.6 6237.1 6260.1 6242.6 6259.5 6267.8 6288.3 6289.9 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:74032.46 Median:41276.19 stddev:163728.30 (396.7%) stddev-sans-first:164094.45 18:50:17 INFO - PROCESS | 1634 | Values: 473.7 487.3 494.6 523.5 603.7 40845.0 42911.6 42934.9 42946.1 43365.3 37857.6 44723.8 44727.8 44811.6 44838.0 9631.5 9832.4 9897.9 9902.4 9918.3 8264.8 8597.4 9676.8 9775.0 9785.4 9721.0 9740.6 9756.7 9825.5 9827.8 8098.8 9760.4 9805.3 9809.9 9817.9 9738.4 9763.2 9769.2 9843.3 9858.3 42472.0 42487.9 42836.6 43008.1 43136.8 3361.5 3478.7 4024.7 4033.8 4188.7 30177.4 30313.1 30506.9 31785.8 32722.8 3171.1 3287.6 3330.3 3356.4 3359.5 508657.0 510564.0 511830.0 513662.0 515044.0 63853.0 64469.4 64476.7 64725.7 65090.7 67687.0 68498.3 68692.7 68723.0 68883.3 16479.9 16767.0 16829.2 17222.7 17250.9 10516.0 10539.8 10567.0 10570.3 10628.1 16506.4 18168.3 19682.2 19931.3 19966.7 3292.2 3395.4 3400.9 8071.4 8159.9 984.4 1024.7 1047.7 1101.2 1123.4 27396.6 29616.6 29834.1 30503.2 30605.7 857.8 1040.5 1108.2 1111.6 1168.7 15649.0 15896.3 15991.4 16004.0 16142.6 681604.0 682497.0 688293.0 688437.0 690292.5 59082.7 59650.4 59762.0 59887.7 60194.4 1403.4 1408.3 1429.7 1432.1 1433.8 29910.0 30318.1 30371.2 30646.8 31015.7 53156.0 53340.5 53355.7 53451.5 53666.5 621339.9 623077.0 624291.8 625995.0 626053.0 27391.9 27415.5 27787.9 27812.4 28043.3 45153.5 46890.1 47070.0 47383.3 47840.0 39090.4 39505.0 39577.4 41245.6 41306.8 39291.2 39711.0 39713.6 40214.0 40938.0 37181.1 38742.4 39013.6 39023.0 39235.6 29051.6 31218.5 31661.8 31709.5 32037.7 21516.0 26414.9 26492.5 26632.0 26723.0 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:35604.09 Median:12651.87 stddev:103770.26 (820.2%) stddev-sans-first:104027.66 18:50:17 INFO - PROCESS | 1634 | Values: 381.9 385.2 389.2 396.8 414.4 16168.6 20076.3 20352.8 21311.5 21359.5 20317.2 20509.6 20536.7 20921.5 21071.7 5217.8 5491.4 5495.3 5496.9 5613.9 5383.0 5529.2 5571.0 5593.6 5612.0 5131.7 5499.6 5570.4 5629.2 5630.1 4452.7 4928.9 5252.1 5561.1 5620.2 4164.1 4782.6 4866.7 4959.9 5056.3 13600.9 13830.7 20694.8 22995.7 25939.7 2308.1 2308.2 2441.7 2663.0 2750.7 9923.7 10041.3 10079.2 10359.8 10558.9 1698.7 1802.6 1861.5 1889.6 1935.6 124119.4 125510.4 128236.4 129259.4 131044.3 11536.3 11814.6 11910.1 11995.3 12134.9 11817.4 12495.9 13394.7 15194.4 15246.9 6701.8 8299.4 8420.8 8461.2 8522.0 8686.4 8735.2 8746.3 8863.6 8877.9 4007.1 4078.2 4093.6 4101.8 4128.4 1702.2 1978.9 2023.9 2027.8 2029.3 832.9 843.0 856.7 876.7 1019.7 20258.7 20472.0 20642.8 20848.1 21019.9 766.4 928.3 940.9 1017.5 1026.6 10544.7 10696.5 10761.4 10774.5 10809.9 612845.0 614101.0 621423.0 629235.0 630268.0 89556.6 109553.5 132739.0 137304.0 140587.3 992.8 1037.4 1046.2 1048.2 1049.1 18604.7 18837.8 19148.4 19236.4 19242.4 56304.4 56816.0 57366.7 58814.1 59076.7 55566.7 79494.2 83341.0 84556.7 86978.0 6312.6 8146.6 8261.4 8285.5 8345.1 12326.4 12649.8 12853.6 12938.6 13072.2 8470.4 11082.9 11155.6 11336.7 11361.1 10925.8 10989.4 11075.8 11078.6 11254.0 11121.8 11254.1 11294.5 11400.5 11640.9 6804.0 8210.8 8313.5 8349.0 8509.4 9048.6 9226.8 10009.2 11442.0 11497.5 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5511.25 Median:5273.87 stddev:2969.03 (56.3%) stddev-sans-first:2961.56 18:50:17 INFO - PROCESS | 1634 | Values: 1437.0 1505.5 1719.8 1838.6 1873.2 7679.5 7934.6 8139.7 8591.6 8612.8 8086.7 8376.1 8394.8 8503.0 8508.2 2109.5 2339.2 2379.6 2407.4 2413.5 2408.7 2427.4 2434.9 2449.6 2464.6 2375.3 2387.4 2403.5 2404.6 2409.5 2354.8 2372.6 2377.8 2379.2 2379.5 2408.0 2416.5 2431.5 2435.3 2439.3 8426.4 8502.7 8551.8 8567.2 8582.0 5195.6 5290.4 5330.9 5331.4 5344.0 3411.6 3472.9 3587.3 3609.9 3621.3 980.8 1015.4 1045.3 1090.4 1111.0 10869.7 10911.8 10914.9 10943.3 10945.0 4542.6 4668.8 4746.0 4752.8 4800.8 9416.4 9433.0 9470.7 9494.1 9503.6 5253.6 5256.5 5257.3 5292.8 5301.5 4365.6 4373.2 4834.8 4847.3 4870.1 3893.6 3903.4 3979.8 3993.2 4030.3 1297.5 1305.8 1306.6 1311.4 1318.8 793.0 837.3 875.7 932.9 938.4 5843.6 5894.6 5940.1 6029.7 6143.6 769.2 937.4 956.5 963.9 1014.2 3440.7 3495.7 3951.6 4003.5 4063.2 9873.1 9921.0 10153.1 10210.8 10258.5 9906.8 10166.9 10188.4 10212.3 10218.5 7784.2 7857.3 7865.7 7896.1 8026.1 5658.4 5695.2 5790.5 5927.3 5957.2 9079.0 9219.2 9368.1 9492.8 9493.0 8058.0 9421.1 9431.6 9488.8 9632.0 5888.4 6547.2 6654.3 7175.1 7302.6 7761.1 7919.8 7982.6 7995.5 8011.3 7074.5 7115.3 7291.1 7311.7 7315.6 6809.9 6945.2 6972.9 7065.4 7082.0 6700.4 6700.4 6740.0 6783.5 6786.7 4918.6 4927.2 4932.9 4949.6 4955.2 4924.7 4940.8 4965.9 5001.8 5014.5 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4706.72 Median:5316.17 stddev:1456.03 (27.4%) stddev-sans-first:1422.25 18:50:17 INFO - PROCESS | 1634 | Values: 311.2 317.1 323.4 324.4 324.9 5463.4 5601.7 5623.9 5763.5 5764.8 5335.8 5554.3 5575.9 5587.9 5592.3 3552.5 4290.1 4308.9 4331.1 4331.7 3657.6 4263.3 4297.3 4346.6 4355.2 3491.5 3940.0 4207.7 4253.7 4286.8 3536.9 4248.2 4264.7 4292.7 4314.6 3082.4 3235.9 3740.2 3748.2 3767.7 4436.8 5087.6 5370.6 5413.6 5485.6 3973.7 4276.5 4891.9 4904.5 4934.6 5676.5 5880.0 5899.0 5905.5 5969.8 3735.0 4045.7 4096.7 4108.6 4119.6 5364.4 6209.3 6226.6 6238.4 6274.2 4124.2 4157.1 4162.9 4176.9 4199.4 3797.3 3869.8 4163.7 4185.9 4185.9 3976.8 4542.8 4864.8 4865.5 4897.6 5206.5 5399.4 5641.7 5975.8 5994.7 1086.5 1098.9 1319.5 1335.8 1342.5 889.2 898.7 912.7 918.3 923.4 4263.1 4599.7 5201.4 5301.7 5307.9 5250.2 5739.2 5826.9 5853.8 5943.5 5331.5 5388.8 5438.8 5453.0 5610.5 2584.0 2793.9 3111.5 3128.0 3130.8 5045.8 5242.4 5843.8 6055.9 6261.6 4946.5 5526.0 5986.6 6013.5 6019.8 5061.3 5106.6 5197.7 5870.6 6135.6 5433.4 5628.6 5790.4 6000.9 6033.2 4818.9 5333.0 5398.4 5636.8 5678.5 5043.5 5900.7 6061.6 6171.2 6183.3 3894.7 3959.4 3991.5 4207.6 4258.5 5306.7 6050.6 6100.6 6104.3 6166.7 5815.7 5994.4 6005.6 6043.2 6070.7 5083.7 5283.8 5638.2 5998.8 6048.1 5395.4 5515.8 5535.9 5658.8 5891.5 5118.7 5849.4 6006.5 6067.9 6085.8 5324.4 5521.0 5633.0 5727.8 5921.8 18:50:17 INFO - PROCESS | 1634 | 18:50:17 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20446.82 Median:25919.36 stddev:29239.10 (112.8%) stddev-sans-first:29283.40 18:50:17 INFO - PROCESS | 1634 | Values: 666.0 673.8 696.2 737.9 830.8 12680.4 12830.6 14624.7 15192.0 15444.7 13927.7 13975.5 14018.6 14783.3 15363.2 828.2 850.1 858.8 870.3 873.1 816.6 829.6 857.6 858.6 861.4 860.1 864.2 882.9 902.4 906.5 857.7 873.4 888.8 900.4 906.0 652.1 657.0 669.7 669.9 671.0 14637.8 14902.5 14959.9 15045.8 15178.5 13621.2 13709.0 14096.8 14159.8 14237.1 11542.5 11588.2 11942.5 11956.5 12048.2 3691.7 3743.2 3751.9 3881.0 3901.5 92465.1 93072.0 93146.6 93856.5 94930.5 10345.3 10728.8 10792.8 10865.8 11073.4 20549.0 20681.9 20731.6 20794.0 20860.0 7897.9 7904.6 7952.8 7960.2 8092.8 4425.8 4431.8 4547.2 4568.9 4589.4 3446.3 3502.7 3556.3 3565.2 3571.7 637.9 642.0 646.2 650.2 658.5 607.6 676.7 679.4 688.9 690.8 9130.8 9268.7 9494.8 9541.8 9636.5 538.6 601.0 631.6 697.1 702.9 4636.8 4690.7 4719.2 4720.8 4747.3 75159.6 92721.5 114119.4 116041.0 119874.4 61788.4 87852.0 94019.1 95261.0 97642.0 76841.6 78488.0 78804.2 80774.0 88692.0 8146.6 9620.3 9824.2 9983.8 10072.4 47148.0 53707.5 54235.9 55945.7 56394.7 71284.6 72693.0 74260.0 77312.3 80308.6 12390.0 13164.9 13237.4 13269.0 13316.6 13987.7 14614.7 14791.3 14928.0 15488.5 11675.7 12625.1 13666.5 13875.2 14080.3 13551.5 13685.4 13757.7 13862.4 13937.8 12832.5 12957.6 13496.8 13522.7 13645.4 11150.3 11419.1 11530.8 11818.6 12026.8 11508.9 11563.7 11638.7 11665.4 11777.6 18:50:17 INFO - PROCESS | 1634 | -------- Summary: end -------- 18:50:17 INFO - PROCESS | 1634 | 18:50:18 INFO - TEST-INFO | 1634: exit 0 18:50:18 INFO - TEST-OK | dromaeo_css | took 1114658ms 18:50:18 INFO - TEST-START | kraken 18:50:18 INFO - operating with platform_type : mac_ 18:50:18 INFO - Initialising browser for kraken test... 18:50:18 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpi4m2Pf/profile http://localhost:49240/getInfo.html) 18:50:20 INFO - PROCESS | 1645 | 2017-01-24 18:50:20.826 plugin-container[1647:9838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 18:50:20 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:50:20 INFO - PROCESS | 1645 | 2017-01-24 18:50:20.827 plugin-container[1647:9838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 18:50:20 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:50:20 INFO - PROCESS | 1645 | 2017-01-24 18:50:20.827 plugin-container[1647:9838] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:50:20 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 18:50:20 INFO - PROCESS | 1645 | colorDepth:24 18:50:20 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 18:50:20 INFO - PROCESS | 1645 | __metrics 18:50:21 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 18:50:21 INFO - TEST-INFO | 1645: exit 0 18:50:21 INFO - Browser initialized. 18:50:21 INFO - Running cycle 1/1 for kraken test... 18:50:21 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpi4m2Pf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:24 INFO - PROCESS | 1648 | 2017-01-24 18:50:24.134 plugin-container[1649:9966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:50:24 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:50:24 INFO - PROCESS | 1648 | 2017-01-24 18:50:24.134 plugin-container[1649:9966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 18:50:24 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:50:24 INFO - PROCESS | 1648 | 2017-01-24 18:50:24.134 plugin-container[1649:9966] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:50:33 INFO - PROCESS | 1648 | RSS: Main: 177328128 18:50:33 INFO - PROCESS | 1648 | 18:50:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:37 INFO - PROCESS | 1648 | RSS: Main: 189992960 18:50:37 INFO - PROCESS | 1648 | 18:50:40 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 18:50:40 INFO - PROCESS | 1648 | RSS: Main: 197906432 18:50:40 INFO - PROCESS | 1648 | 18:50:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 18:50:44 INFO - PROCESS | 1648 | RSS: Main: 201650176 18:50:44 INFO - PROCESS | 1648 | 18:50:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 18:50:47 INFO - PROCESS | 1648 | RSS: Main: 203075584 18:50:47 INFO - PROCESS | 1648 | 18:50:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:49 INFO - PROCESS | 1648 | RSS: Main: 203042816 18:50:49 INFO - PROCESS | 1648 | 18:50:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:53 INFO - PROCESS | 1648 | RSS: Main: 207380480 18:50:53 INFO - PROCESS | 1648 | 18:50:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:56 INFO - PROCESS | 1648 | RSS: Main: 207335424 18:50:56 INFO - PROCESS | 1648 | 18:51:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 18:51:00 INFO - PROCESS | 1648 | RSS: Main: 206741504 18:51:00 INFO - PROCESS | 1648 | 18:51:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:03 INFO - PROCESS | 1648 | RSS: Main: 205991936 18:51:03 INFO - PROCESS | 1648 | 18:51:04 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:05 INFO - PROCESS | 1648 | RSS: Main: 206028800 18:51:05 INFO - PROCESS | 1648 | 18:51:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:08 INFO - PROCESS | 1648 | RSS: Main: 206020608 18:51:08 INFO - PROCESS | 1648 | 18:51:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:12 INFO - PROCESS | 1648 | RSS: Main: 206503936 18:51:12 INFO - PROCESS | 1648 | 18:51:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:15 INFO - PROCESS | 1648 | RSS: Main: 206266368 18:51:15 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 18:51:17 INFO - PROCESS | 1648 | RSS: Main: 207695872 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | __start_tp_report 18:51:17 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:51:17 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:51:17 INFO - PROCESS | 1648 | |i|pagename|runs| 18:51:17 INFO - PROCESS | 1648 | |0;ai-astar;104;117;117;118;118;119;117;120;119;121 18:51:17 INFO - PROCESS | 1648 | |1;audio-beat-detection;141;110;110;141;109;113;109;120;137;110 18:51:17 INFO - PROCESS | 1648 | |2;audio-dft;192;200;196;199;196;196;199;199;189;279 18:51:17 INFO - PROCESS | 1648 | |3;audio-fft;85;60;60;85;64;60;61;61;77;59 18:51:17 INFO - PROCESS | 1648 | |4;audio-oscillator;81;72;89;71;69;71;85;71;72;87 18:51:17 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;102;90;94;136;87;92;87;90;89 18:51:17 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;97;97;113;96;125;152;149;96;95 18:51:17 INFO - PROCESS | 1648 | |7;imaging-desaturate;118;129;118;81;136;139;109;83;79;118 18:51:17 INFO - PROCESS | 1648 | |8;json-parse-financial;54;58;54;57;50;56;68;88;87;90 18:51:17 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;68;72;67;70;77;74;71;70;81;61 18:51:17 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;99;79;91;76;91;100;104;70;98;103 18:51:17 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;176;155;154;163;160;154;169;157;211;159 18:51:17 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;168;170;165;165;166;166;164;166;175;172 18:51:17 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;65;66;66;67;67;68;66;67;65;68 18:51:17 INFO - PROCESS | 1648 | __end_tp_report 18:51:17 INFO - PROCESS | 1648 | __start_cc_report 18:51:17 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,931 18:51:17 INFO - PROCESS | 1648 | __end_cc_report 18:51:17 INFO - PROCESS | 1648 | __startTimestamp1485312677569__endTimestamp 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:51:17 INFO - PROCESS | 1648 | Number of tests: 14 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:117.00 Median:118.50 stddev:4.76 (4.0%) stddev-sans-first:1.42 18:51:17 INFO - PROCESS | 1648 | Values: 104.0 117.0 117.0 118.0 118.0 119.0 117.0 120.0 119.0 121.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:116.50 stddev:13.99 (12.0%) stddev-sans-first:12.61 18:51:17 INFO - PROCESS | 1648 | Values: 141.0 110.0 110.0 141.0 109.0 113.0 109.0 120.0 137.0 110.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:204.50 Median:199.00 stddev:26.40 (13.3%) stddev-sans-first:27.62 18:51:17 INFO - PROCESS | 1648 | Values: 192.0 200.0 196.0 199.0 196.0 196.0 199.0 199.0 189.0 279.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:67.20 Median:62.50 stddev:10.75 (17.2%) stddev-sans-first:9.27 18:51:17 INFO - PROCESS | 1648 | Values: 85.0 60.0 60.0 85.0 64.0 60.0 61.0 61.0 77.0 59.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:76.50 stddev:7.79 (10.2%) stddev-sans-first:8.11 18:51:17 INFO - PROCESS | 1648 | Values: 81.0 72.0 89.0 71.0 69.0 71.0 85.0 71.0 72.0 87.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:90.00 stddev:14.81 (16.5%) stddev-sans-first:15.56 18:51:17 INFO - PROCESS | 1648 | Values: 90.0 102.0 90.0 94.0 136.0 87.0 92.0 87.0 90.0 89.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:95.50 stddev:22.88 (24.0%) stddev-sans-first:23.38 18:51:17 INFO - PROCESS | 1648 | Values: 94.0 97.0 97.0 113.0 96.0 125.0 152.0 149.0 96.0 95.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:111.00 Median:137.50 stddev:22.59 (16.4%) stddev-sans-first:23.82 18:51:17 INFO - PROCESS | 1648 | Values: 118.0 129.0 118.0 81.0 136.0 139.0 109.0 83.0 79.0 118.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:63.00 stddev:15.96 (25.3%) stddev-sans-first:16.31 18:51:17 INFO - PROCESS | 1648 | Values: 54.0 58.0 54.0 57.0 50.0 56.0 68.0 88.0 87.0 90.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:5.51 (7.7%) stddev-sans-first:5.73 18:51:17 INFO - PROCESS | 1648 | Values: 68.0 72.0 67.0 70.0 77.0 74.0 71.0 70.0 81.0 61.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:85.00 stddev:12.10 (14.2%) stddev-sans-first:12.49 18:51:17 INFO - PROCESS | 1648 | Values: 99.0 79.0 91.0 76.0 91.0 100.0 104.0 70.0 98.0 103.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:165.80 Median:161.50 stddev:17.38 (10.8%) stddev-sans-first:18.03 18:51:17 INFO - PROCESS | 1648 | Values: 176.0 155.0 154.0 163.0 160.0 154.0 169.0 157.0 211.0 159.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:167.00 stddev:3.56 (2.1%) stddev-sans-first:3.77 18:51:17 INFO - PROCESS | 1648 | Values: 168.0 170.0 165.0 165.0 166.0 166.0 164.0 166.0 175.0 172.0 18:51:17 INFO - PROCESS | 1648 | 18:51:17 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 18:51:17 INFO - PROCESS | 1648 | Values: 65.0 66.0 66.0 67.0 67.0 68.0 66.0 67.0 65.0 68.0 18:51:17 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:51:17 INFO - PROCESS | 1648 | 18:51:18 INFO - TEST-INFO | 1648: exit 0 18:51:18 INFO - TEST-OK | kraken | took 60446ms 18:51:18 INFO - SUITE-END | took 1175s 18:51:18 INFO - Completed test suite (00:19:35) 18:51:18 INFO - javascript benchmark 18:51:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7365.773984301048, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3412.7099196568292, 3554.484599733039, 3618.402963511019, 3622.655847694469, 3628.909277268068, 5959.940400595992, 5990.970045149779, 5998.970005149979, 6011.0, 6019.428154325337, 6008.909866351997, 6083.148359229712, 6085.8741132890445, 6089.147519347295, 6101.328853826087, 5338.572914166867, 5434.538064264542, 5444.3194600674915, 5457.754401051942, 5527.170924361338, 5318.228856815759, 5447.782088716446, 5507.752151153188, 5534.916976245339, 5572.498475137237, 5341.679499230039, 5349.545288650469, 5497.725113744297, 5505.366882808468, 5507.118860982244, 5460.96241714075, 5471.945280547203, 5487.368952570466, 5517.200005999148, 5582.972085139589, 5328.626996110235, 5429.239906413105, 5448.700321482317, 5453.127499600045, 5476.3702174250075, 5800.158976948361, 6087.817365479043, 6099.603525770811, 6111.84720381994, 6116.541259405562, 5408.756605952742, 6228.937710622881, 6234.750609975596, 6252.187215661935, 6265.404786545271, 5325.920111198335, 6396.648184349859, 6404.167458230447, 6441.774537891151, 6488.026795980593, 6172.290186628506, 6172.4136207060255, 6176.289726681399, 6179.721912513993, 6183.907241391383, 6515.902261466082, 6528.510361722795, 6533.901991470132, 6605.1083103780375, 6621.86756264872, 6316.905246421307, 6319.557630965788, 6320.71556779946, 6320.810375688737, 6323.20959880015, 6220.160278362363, 6248.968755156195, 6456.54804163704, 6480.287168411471, 6485.4811615070685, 6244.875102497925, 6313.273973493016, 6323.336049714806, 6372.171617689671, 6394.584352017104, 5269.631125821155, 6349.746010159634, 6350.983842585257, 6392.169018027626, 6394.5843520171975, 5208.739563021833, 5230.97384513075, 5240.187770895517, 5243.606729495227, 5245.344331958505, 3356.0, 4029.2143032109043, 4091.2226676931286, 4528.705634133771, 4612.492625811158, 5971.206866438749, 6219.40915613016, 6224.253089629183, 6227.252729672377, 6229.688515574203, 6407.231132264158, 6456.386643268882, 6487.545871789024, 6492.805215843532, 6510.218773747086, 6090.847728806727, 6096.603720758137, 6127.433875357673, 6139.676133582418, 6147.692615369214, 5647.745851436695, 5685.630434021776, 5702.971485142548, 5740.741666624928, 5775.075987841925, 6637.701303441357, 6646.468282537386, 6671.299513551007, 6680.966595166993, 6687.1975362956755, 6167.722452489738, 6284.962981108066, 6452.290248072614, 6460.160179176865, 6466.353364663496, 6525.216973962965, 6551.148350714377, 6594.175728033996, 6657.400833924777, 6670.099536562696, 6031.969840150947, 6328.778492752732, 6334.461570766352, 6340.841478962879, 6346.746130154742, 6264.780732674334, 6364.395382438661, 6368.426841584094, 6441.291457939717, 6452.9677351612945, 6211.316755156839, 6219.751209951552, 6258.0, 6262.2172228471445, 6321.557490975588, 6263.467605253604, 6275.560710750204, 6293.716782744696, 6293.96853015732, 6295.842603934938, 6109.358517355614, 6141.508679305734, 6235.158253635883, 6265.843353916189, 6321.241451025906, 6175.44421002112, 6183.536234782499, 6191.597546159485, 6237.126802247598, 6260.123582698517, 6242.563020588515, 6259.467945224525, 6267.811965641038, 6288.276848162337, 6289.874202516015], "value": 5912.043174730519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.68263263613375, 487.3347880143605, 494.5549005894694, 523.5183631059425, 603.7434090511531, 40845.0, 42911.57088429115, 42934.92662683434, 42946.14107717848, 43365.34951975723, 37857.62142378576, 44723.77638111805, 44727.77636111815, 44811.551884481145, 44838.0, 9631.518424078786, 9832.360896541702, 9897.901020989806, 9902.405855648649, 9918.25613079022, 8264.752057438256, 8597.398182127254, 9676.806463870718, 9775.0, 9785.363951343175, 9720.951395243013, 9740.610375584969, 9756.658516951908, 9825.459599722013, 9827.803443931118, 8098.838023239532, 9760.365576237593, 9805.264605154642, 9809.852852207223, 9817.90182098177, 9738.367006144577, 9763.170130538912, 9769.218462523053, 9843.277426450413, 9858.260630452745, 42472.0, 42487.9378015549, 42836.57163428357, 43008.139837203395, 43136.784316078534, 3361.512580675813, 3478.7043101336417, 4024.6579040781276, 4033.757974521538, 4188.748675079505, 30177.396452071054, 30313.090607281596, 30506.932257371107, 31785.84107079473, 32722.83638581792, 3171.1279398165457, 3287.621923478807, 3330.317284956578, 3356.3790698720813, 3359.5128706337687, 508657.0, 510564.0, 511830.0, 513662.0, 515044.0, 63853.0, 64469.35530644727, 64476.67761661162, 64725.70548589096, 65090.67454662791, 67686.9846952296, 68498.3150168492, 68692.656536718, 68723.0, 68883.31116688868, 16479.92880462766, 16766.993980361218, 16829.242684079247, 17222.741658875126, 17250.913745431193, 10516.0, 10539.84190237147, 10567.0, 10570.260081794355, 10628.096611788085, 16506.42227522031, 18168.339192081883, 19682.212711491666, 19931.302404416067, 19966.70049949252, 3292.226326813197, 3395.3548825723033, 3400.931981360359, 8071.354291656771, 8159.877601835978, 984.3945973226557, 1024.7130803375067, 1047.6542740895486, 1101.1938686001172, 1123.3765260280459, 27396.58905116425, 29616.555751663742, 29834.10497685073, 30503.23741906426, 30605.693943060283, 857.7898414888392, 1040.4641609571077, 1108.2131686502673, 1111.5998240633367, 1168.6669299249672, 15648.982816116915, 15896.284667190004, 15991.36034558628, 16004.0, 16142.581677038825, 681604.0, 682497.0, 688293.0, 688437.0, 690292.5485372641, 59082.70458647765, 59650.40349596535, 59762.0, 59887.70056149778, 60194.39805601975, 1403.4245959156697, 1408.2677007955806, 1429.7426463236718, 1432.0691550492056, 1433.7921001454731, 29910.0, 30318.136047775275, 30371.2407189822, 30646.84676576603, 31015.68984310128, 53156.0, 53340.46659533355, 53355.7332213352, 53451.46548534465, 53666.46333536615, 621339.8933005306, 623077.0, 624291.7570824233, 625995.0, 626053.0, 27391.904323826824, 27415.451690966467, 27787.861060695377, 27812.44375112527, 28043.298917527223, 45153.54846451625, 46890.06219875652, 47070.0, 47383.28925066058, 47840.0, 39090.4136437948, 39505.012374690865, 39577.406338904366, 41245.58754412417, 41306.793466032475, 39291.21417571575, 39711.0, 39713.60286397099, 40214.0, 40938.0, 37181.07047323838, 38742.418863717634, 39013.609863900994, 39023.024424389616, 39235.60764392434, 29051.564226536197, 31218.531722023734, 31661.8416907914, 31709.5243571342, 32037.67962320347, 21516.0, 26414.867925660248, 26492.470150597266, 26632.0, 26723.0], "value": 20240.33976882868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.8968878402831, 385.22569635033574, 389.1671822300277, 396.77978721809393, 414.3660199894161, 16168.595785105348, 20076.297329593468, 20352.796472035276, 21311.467213319655, 21359.466013349655, 20317.18731250748, 20509.589808203826, 20536.691949620767, 20921.47696307593, 21071.68392474114, 5217.817376391837, 5491.368492623339, 5495.313085864267, 5496.945030549703, 5613.943860561403, 5382.9730851345685, 5529.225908372831, 5570.972145139269, 5593.608447408663, 5611.971940140294, 5131.692098474085, 5499.642523235997, 5570.359408668014, 5629.183768353586, 5630.071038278679, 4452.666050046243, 4928.926066109011, 5252.107141785906, 5561.082421400484, 5620.156976453523, 4164.126143235556, 4782.641301902337, 4866.732329721864, 4959.900801983977, 5056.26684130799, 13600.931995340059, 13830.723385532234, 20694.793052069435, 22995.655065174036, 25939.74060259411, 2308.0883051194683, 2308.1575225042907, 2441.7069951605745, 2662.9747547194274, 2750.7386798254133, 9923.702288931343, 10041.297109202358, 10079.244056695778, 10359.79280414395, 10558.947205263925, 1698.7282034874484, 1802.6124383257531, 1861.4508719927653, 1889.6126294109674, 1935.6225536020481, 124119.3794031033, 125510.37244813809, 128236.3588182053, 129259.35370323183, 131044.34477827646, 11536.307821530734, 11814.586489472827, 11910.106741994385, 11995.340256285988, 12134.878651213376, 11817.409129543488, 12495.875041249472, 13394.732105357838, 15194.392224310905, 15246.923765381323, 6701.765438209639, 8299.377546684023, 8420.78948026292, 8461.196186362286, 8522.0, 8686.39195256326, 8735.213830755136, 8746.256568191648, 8863.601137948808, 8877.866831997526, 4007.1184339445276, 4078.163976384834, 4093.5701751315682, 4101.835926562963, 4128.442660240889, 1702.2425020865844, 1978.9307374241832, 2023.908924098419, 2027.7668068172054, 2029.2852539604012, 832.9296853543168, 843.0, 856.6744637037885, 876.7238319929329, 1019.6584144311738, 20258.69611955822, 20472.0, 20642.793572064387, 20848.081268622405, 21019.8949005254, 766.3792328214164, 928.3455164109287, 940.9294302927308, 1017.4963393120453, 1026.625281772148, 10544.683659490227, 10696.465176741132, 10761.354318740901, 10774.46127693628, 10809.891901080888, 612845.0, 614101.0, 621423.0, 629235.0, 630268.0, 89556.5522172385, 109553.45223273992, 132739.0, 137304.0, 140587.29706351404, 992.8411454167443, 1037.4034929915179, 1046.2048842879315, 1048.2138396202847, 1049.1134990932649, 18604.7209291858, 18837.811621883604, 19148.425547233604, 19236.422907312804, 19242.422727318204, 56304.43695562992, 56816.0, 57366.7131664339, 58814.11778823406, 59076.70461647664, 55566.72216638891, 79494.205057951, 83341.0, 84556.73164902655, 86978.0, 6312.589681670676, 8146.633401497065, 8261.421700480909, 8285.54429506383, 8345.123762005145, 12326.445309960895, 12649.810252846031, 12853.550125745554, 12938.611841644766, 13072.215667060007, 8470.364722645703, 11082.889171108509, 11155.609553665907, 11336.659900203007, 11361.091112711129, 10925.781484370427, 10989.395583242998, 11075.778484430106, 11078.612248571262, 11254.0, 11121.83317250258, 11254.099672026383, 11294.491747871201, 11400.543978240778, 11640.88359116398, 6804.0, 8210.794730131794, 8313.542755148525, 8349.0, 8509.446885952393, 9048.638054477748, 9226.815463690822, 10009.199264059003, 11442.0, 11497.482613282255], "value": 9370.089977052176, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1436.97844532332, 1505.4580351073614, 1719.845213930746, 1838.6414649143424, 1873.2319748902953, 7679.539227646345, 7934.563599002053, 8139.674413023479, 8591.570421478933, 8612.827743445127, 8086.67653293869, 8376.120507346732, 8394.790130246747, 8503.0, 8508.234258916696, 2109.48317662173, 2339.2046704120594, 2379.6311571706415, 2407.374082738492, 2413.54142712884, 2408.7109546854404, 2427.405285704996, 2434.926952191437, 2449.5590793657134, 2464.6303054541872, 2375.287413775869, 2387.3673476528734, 2403.507281007398, 2404.5671779079757, 2409.481961378312, 2354.82338824588, 2372.608519594265, 2377.8097752179783, 2379.1910750344878, 2379.4765151666606, 2408.000679717915, 2416.4804567018, 2431.538007778516, 2435.2937648082034, 2439.341377827994, 8426.368022398345, 8502.702405415781, 8551.786205344853, 8567.228949394585, 8582.0, 5195.558377537914, 5290.418054014055, 5330.92003619946, 5331.360236771573, 5344.038073146832, 3411.64177761334, 3472.9305413891834, 3587.318409502186, 3609.9278014439565, 3621.2938476996997, 980.8283550378726, 1015.3700520638672, 1045.278757657214, 1090.3948308688675, 1111.0112000319723, 10869.728256793564, 10911.83632245517, 10914.945425272823, 10943.343399395982, 10944.99999999992, 4542.591166795004, 4668.8132474701315, 4746.0, 4752.809887604457, 4800.807967681254, 9416.387934784218, 9433.0, 9470.715878523655, 9494.050594940589, 9503.57233924475, 5253.632245742838, 5256.474352564713, 5257.316548848626, 5292.814751483679, 5301.5493683037375, 4365.628921541641, 4373.169097871394, 4834.830780922651, 4847.297141914473, 4870.05034018363, 3893.5717071122153, 3903.394973779069, 3979.8209080590864, 3993.1813978134414, 4030.3349947258375, 1297.4874924404808, 1305.830242068544, 1306.6080175947368, 1311.4491913396396, 1318.8483324417623, 792.9920700792918, 837.3468694418364, 875.6584931876573, 932.8693982842408, 938.394735395666, 5843.626747714284, 5894.557908156906, 5940.108983652487, 6029.668368239789, 6143.600665956698, 769.169297159078, 937.4188003437912, 956.4739393333639, 963.8987906269881, 1014.2494554030112, 3440.6559344065395, 3495.685388315064, 3951.6245956634075, 4003.499562554681, 4063.2482990646827, 9873.062059104373, 9920.950395247977, 10153.13698335635, 10210.795784084277, 10258.53836577341, 9906.752331191778, 10166.949165254275, 10188.388696678223, 10212.336198147097, 10218.489075546193, 7784.221577842283, 7857.253560911704, 7865.685372585032, 7896.052473703192, 8026.117127116013, 5658.405867383865, 5695.202671625892, 5790.478856902899, 5927.288725352985, 5957.195778569838, 9078.954605227196, 9219.216366608645, 9368.110029547182, 9492.762680932756, 9493.003234660537, 8057.959710201411, 9421.057894210524, 9431.57557909882, 9488.810223795348, 9631.988641192855, 5888.440598143169, 6547.181602299713, 6654.334566543307, 7175.067241258812, 7302.56184628924, 7761.107472640719, 7919.84160316802, 7982.640781164745, 7995.520268783892, 8011.278984891388, 7074.504784665024, 7115.2528984455885, 7291.125064992235, 7311.707531698672, 7315.561066336037, 6809.89785153233, 6945.166580010431, 6972.860542789217, 7065.399441047363, 7081.964590177016, 6700.363465470772, 6700.43046341047, 6740.0, 6783.525153239226, 6786.694598743167, 4918.5573298403315, 4927.162382394929, 4932.8520144395725, 4949.554540091409, 4955.231939049453, 4924.679895806761, 4940.802367905244, 4965.851024469127, 5001.824936127218, 5014.52362025607], "value": 4491.75760425655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.21728851937365, 317.1421305368977, 323.3855674218983, 324.4192894718454, 324.8797944760439, 5463.426340234278, 5601.719914004304, 5623.887522249553, 5763.481286684198, 5764.769409223632, 5335.759890804913, 5554.305711786027, 5575.944240557593, 5587.859169564001, 5592.3009623797025, 3552.5381700378916, 4290.120525292323, 4308.913821723564, 4331.133773245348, 4331.7184383015165, 3657.5610926688832, 4263.2965560682605, 4297.269464191095, 4346.608805207531, 4355.237833379162, 3491.476278558211, 3939.9756063423574, 4207.747535147885, 4253.680973926952, 4286.807093680776, 3536.929261414783, 4248.196190514246, 4264.722793018444, 4292.720973136736, 4314.576189857371, 3082.352705931757, 3235.8867439639735, 3740.17716102457, 3748.194138260287, 3767.6609105180664, 4436.778161091933, 5087.56755675768, 5370.570354371641, 5413.621046526745, 5485.561155107582, 3973.721839471238, 4276.48682158143, 4891.853244402673, 4904.5340692634145, 4934.55588996992, 5676.460736230051, 5880.0, 5898.991514327681, 5905.527557795366, 5969.791057312973, 3734.9813250933844, 4045.696572757055, 4096.73371230869, 4108.630223279919, 4119.629233368982, 5364.356277246756, 6209.285932117819, 6226.571637061505, 6238.438540531374, 6274.172754619678, 4124.195781822576, 4157.08544120293, 4162.91674166521, 4176.937345939813, 4199.4120823084795, 3797.2785170817456, 3869.787161706077, 4163.708540402204, 4185.853495127717, 4185.874423767231, 3976.8210430530694, 4542.841575398253, 4864.80540778372, 4865.464798872121, 4897.559219670246, 5206.479352064839, 5399.352077750695, 5641.746121424546, 5975.760969561169, 5994.670293133832, 1086.4947799273218, 1098.901098901099, 1319.459021801057, 1335.8063080853221, 1342.5166939901628, 889.1686273334453, 898.6899519665704, 912.7124955638952, 918.2837386838277, 923.3951761596165, 4263.147370525907, 4599.655025873073, 5201.427842937273, 5301.734913254322, 5307.920381194285, 5250.200989718324, 5739.2252045973255, 5826.921155191299, 5853.795117170964, 5943.4547017775685, 5331.5201631853315, 5388.811391601275, 5438.83683489496, 5453.0, 5610.5231055360755, 2583.9870800646254, 2793.8882444701985, 3111.471049921519, 3127.9687203128133, 3130.827804470776, 5045.772940217699, 5242.423333433319, 5843.795467158629, 6055.909161362583, 6261.592996455307, 4946.455889852114, 5525.977694126519, 5986.635279064745, 6013.51891848651, 6019.789307374221, 5061.291419201241, 5106.642535022513, 5197.740112994411, 5870.569723006288, 6135.570510064342, 5433.42430695094, 5628.634138780966, 5790.420957904176, 6000.859407009149, 6033.155358249761, 4818.879528011687, 5333.013392522395, 5398.406175320633, 5636.802711905064, 5678.488935995781, 5043.478260869565, 5900.701845593962, 6061.635961974745, 6171.241196259045, 6183.258009038944, 3894.727369084137, 3959.44567760508, 3991.5409727881665, 4207.600277973587, 4258.531561528292, 5306.665067370344, 6050.5764596479585, 6100.572959892942, 6104.328523862283, 6166.691665416801, 5815.680137592473, 5994.362480508562, 6005.558665920235, 6043.244594425697, 6070.666113363807, 5083.694978301313, 5283.837555737731, 5638.182463542846, 5998.790042348497, 6048.12302216185, 5395.406505284494, 5515.834524964257, 5535.861603459945, 5658.839937812821, 5891.528677705859, 5118.72031992002, 5849.415058494116, 6006.549508786771, 6067.87744267312, 6085.817425477243, 5324.414314425488, 5520.966993025219, 5633.0, 5727.828165155052, 5921.792737254175], "value": 4236.7611347658785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.9667016649166, 673.8214373191105, 696.2237105627228, 737.8931602596102, 830.7715378270976, 12680.429380677868, 12830.588635250115, 14624.70750584989, 15192.0, 15444.69110617787, 13927.721445571107, 13975.510857120003, 14018.649533761662, 14783.334749936252, 15363.23183840809, 828.2380210206594, 850.1498501498502, 858.8110615664543, 870.3472395703222, 873.1137895036543, 816.6121092481084, 829.5520418973747, 857.5840717252128, 858.6179150278098, 861.4142383179435, 860.1398601398602, 864.2437866866492, 882.858742601184, 902.3548163063408, 906.4515967839428, 857.661223816592, 873.4409977614364, 888.7511496780912, 900.3922352412096, 906.0486489186394, 652.0968458684708, 656.957297775643, 669.7019826177353, 669.8712669152494, 670.9899351509682, 14637.780433293508, 14902.478413255483, 14959.878009149357, 15045.774313385307, 15178.468753593681, 13621.182729036289, 13709.040367174302, 14096.788548171786, 14159.78760318596, 14237.145771253654, 11542.538298468051, 11588.188826782129, 11942.522299108112, 11956.461959211769, 12048.216865903776, 3691.7231207659265, 3743.2139250757373, 3751.9249615007548, 3880.9521429213955, 3901.5318161820765, 92465.07534924633, 93072.0, 93146.60280095803, 93856.53071734597, 94930.5253473742, 10345.327553708985, 10728.839067413839, 10792.838107428237, 10865.782684346268, 11073.390963496924, 20549.0, 20681.86249756278, 20731.585368292548, 20794.0, 20859.957002149833, 7897.921020789719, 7904.604769761489, 7952.840943181104, 7960.164182760843, 8092.797680057928, 4425.778711064434, 4431.800568974404, 4547.249703798902, 4568.862934111982, 4589.35748995141, 3446.276281980762, 3502.7372947029075, 3556.270964452281, 3565.2156525564333, 3571.7321200909514, 637.8851806674751, 642.0497663458108, 646.2148489585177, 650.1645385679379, 658.4534836086037, 607.6050567131311, 676.671814170127, 679.3919442099293, 688.9311068893071, 690.7555696503651, 9130.845465313267, 9268.72193834186, 9494.810103797885, 9541.809163816824, 9636.46999415025, 538.5556915544691, 600.9519238460913, 631.5673763472037, 697.0554898113085, 702.8875379939185, 4636.837710680177, 4690.718556886598, 4719.1741445247635, 4720.811167553328, 4747.287906813936, 75159.62420187864, 92721.5363923176, 114119.42940285246, 116041.0, 119874.40062799805, 61788.382116179164, 87852.0, 94019.0598094024, 95261.0473895266, 97642.0, 76841.61579192069, 78488.0, 78804.21195788083, 80774.0, 88692.0, 8146.592670366576, 9620.278479113955, 9824.214062875095, 9983.750406239611, 10072.446015469219, 47148.0, 53707.462925370244, 54235.91528169336, 55945.720271398386, 56394.7180264096, 71284.64357678179, 72693.0, 74260.0, 77312.29406970729, 80308.59845700733, 12390.0, 13164.946804255827, 13237.404316805574, 13269.0, 13316.600501984956, 13987.650308742363, 14614.707705846036, 14791.260436978324, 14928.02967807089, 15488.5353439397, 11675.708107297385, 12625.053121015777, 13666.52167174144, 13875.236861972688, 14080.295985200493, 13551.457941682616, 13685.384157713126, 13757.656058598615, 13862.4455021798, 13937.810735653396, 12832.486700531874, 12957.611271661865, 13496.797548036591, 13522.661933451742, 13645.385957631732, 11150.330980140892, 11419.14356423255, 11530.76938461243, 11818.645440636794, 12026.75946481083, 11508.88491115078, 11563.6530904073, 11638.672265546755, 11665.416729163677, 11777.646670599896], "value": 7483.696590986117, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 117.0, 118.0, 118.0, 119.0, 117.0, 120.0, 119.0, 121.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 110.0, 141.0, 109.0, 113.0, 109.0, 120.0, 137.0, 110.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 200.0, 196.0, 199.0, 196.0, 196.0, 199.0, 199.0, 189.0, 279.0], "value": 204.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 60.0, 85.0, 64.0, 60.0, 61.0, 61.0, 77.0, 59.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 89.0, 71.0, 69.0, 71.0, 85.0, 71.0, 72.0, 87.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 90.0, 94.0, 136.0, 87.0, 92.0, 87.0, 90.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 97.0, 113.0, 96.0, 125.0, 152.0, 149.0, 96.0, 95.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 129.0, 118.0, 81.0, 136.0, 139.0, 109.0, 83.0, 79.0, 118.0], "value": 111.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 54.0, 57.0, 50.0, 56.0, 68.0, 88.0, 87.0, 90.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 67.0, 70.0, 77.0, 74.0, 71.0, 70.0, 81.0, 61.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 79.0, 91.0, 76.0, 91.0, 100.0, 104.0, 70.0, 98.0, 103.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 155.0, 154.0, 163.0, 160.0, 154.0, 169.0, 157.0, 211.0, 159.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 165.0, 165.0, 166.0, 166.0, 164.0, 166.0, 175.0, 172.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 67.0, 68.0, 66.0, 67.0, 65.0, 68.0], "value": 66.5, "unit": "score"}]}]} 18:51:18 INFO - Return code: 0 18:51:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:51:18 ERROR - # TBPL SUCCESS # 18:51:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:18 INFO - 2017-01-24 18:51:18.891 screenresolution[1653:10353] starting screenresolution argv=screenresolution get 18:51:18 INFO - 2017-01-24 18:51:18.894 screenresolution[1653:10353] Display 0: 1600x1200x32@60 18:51:18 INFO - 2017-01-24 18:51:18.900 screenresolution[1654:10356] starting screenresolution argv=screenresolution list 18:51:18 INFO - Available Modes on Display 0 18:51:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:19 INFO - Intel Iris: 18:51:19 INFO - Chipset Model: Intel Iris 18:51:19 INFO - Type: GPU 18:51:19 INFO - Bus: Built-In 18:51:19 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:19 INFO - Vendor: Intel (0x8086) 18:51:19 INFO - Device ID: 0x0a2e 18:51:19 INFO - Revision ID: 0x0009 18:51:19 INFO - Displays: 18:51:19 INFO - Display: 18:51:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:19 INFO - Display Serial Number: 1600x1200 60 18:51:19 INFO - Main Display: Yes 18:51:19 INFO - Mirror: Off 18:51:19 INFO - Online: Yes 18:51:19 INFO - Rotation: Supported 18:51:19 INFO - Return code: 0 18:51:19 INFO - Running post-action listener: _resource_record_post_action 18:51:19 INFO - [mozharness: 2017-01-25 02:51:19.145866Z] Finished run-tests step (success) 18:51:19 INFO - Running post-run listener: _resource_record_post_run 18:51:19 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40253440; Write bytes: 502172672; Read time: 393; Write time: 1660 18:51:19 INFO - TinderboxPrint: CPU usage
54.1% 18:51:19 INFO - TinderboxPrint: I/O read bytes / time
40,253,440 / 393 18:51:19 INFO - TinderboxPrint: I/O write bytes / time
502,172,672 / 1,660 18:51:19 INFO - TinderboxPrint: CPU idle
2,199.5 (45.9%) 18:51:19 INFO - TinderboxPrint: CPU system
967.5 (20.2%) 18:51:19 INFO - TinderboxPrint: CPU user
1,622.7 (33.9%) 18:51:19 INFO - TinderboxPrint: Swap in / out
231,710,720 / 0 18:51:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 181033472; Write bytes: 176060416; Read time: 16599; Write time: 380 18:51:19 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 39311360; Write bytes: 308380672; Read time: 377; Write time: 1216 18:51:19 INFO - Running post-run listener: _upload_blobber_files 18:51:19 INFO - Blob upload gear active. 18:51:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:51:20 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:51:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:51:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:20 INFO - (blobuploader) - INFO - Done attempting. 18:51:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:51:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:21 INFO - (blobuploader) - INFO - Done attempting. 18:51:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:51:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:51:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:23 INFO - (blobuploader) - INFO - Done attempting. 18:51:23 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:23 INFO - Return code: 0 18:51:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07b4ddd7aade373e37e4cc9394eb905d546b7d2d508c26e6ef917b6e145d5868bcb3565484766a9c567a7c26f11c0b4ea08a6bf0025a0aa6dfb9afb910019595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b87ded168bbe55600b46f2592f406faf15365f52144b11c4c473921f37cd243b57f15acce5fe1a48dea39aba27921afe2b3118e363191533702c5ab5731d2d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66764f96768a68731e172da9722636e8153f35ab3bbe48fe8952297cc7a412bfe388190a46a52a51674284574d4c21faa5df0fcc92f29e396dc14bb801f856aa"} 18:51:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:23 INFO - Contents: 18:51:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07b4ddd7aade373e37e4cc9394eb905d546b7d2d508c26e6ef917b6e145d5868bcb3565484766a9c567a7c26f11c0b4ea08a6bf0025a0aa6dfb9afb910019595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b87ded168bbe55600b46f2592f406faf15365f52144b11c4c473921f37cd243b57f15acce5fe1a48dea39aba27921afe2b3118e363191533702c5ab5731d2d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66764f96768a68731e172da9722636e8153f35ab3bbe48fe8952297cc7a412bfe388190a46a52a51674284574d4c21faa5df0fcc92f29e396dc14bb801f856aa"} 18:51:23 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:23 INFO - Copying logs to upload dir... 18:51:23 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.372877 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-24 18:51:24.025876) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 18:51:24.033150) ========= 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.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07b4ddd7aade373e37e4cc9394eb905d546b7d2d508c26e6ef917b6e145d5868bcb3565484766a9c567a7c26f11c0b4ea08a6bf0025a0aa6dfb9afb910019595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b87ded168bbe55600b46f2592f406faf15365f52144b11c4c473921f37cd243b57f15acce5fe1a48dea39aba27921afe2b3118e363191533702c5ab5731d2d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66764f96768a68731e172da9722636e8153f35ab3bbe48fe8952297cc7a412bfe388190a46a52a51674284574d4c21faa5df0fcc92f29e396dc14bb801f856aa"} build_url:https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010827 build_url: 'https://queue.taskcluster.net/v1/task/VR5M3lRhRy-KUew2xAXh3w/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07b4ddd7aade373e37e4cc9394eb905d546b7d2d508c26e6ef917b6e145d5868bcb3565484766a9c567a7c26f11c0b4ea08a6bf0025a0aa6dfb9afb910019595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b87ded168bbe55600b46f2592f406faf15365f52144b11c4c473921f37cd243b57f15acce5fe1a48dea39aba27921afe2b3118e363191533702c5ab5731d2d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66764f96768a68731e172da9722636e8153f35ab3bbe48fe8952297cc7a412bfe388190a46a52a51674284574d4c21faa5df0fcc92f29e396dc14bb801f856aa"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 18:51:24.070680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:51:24.071124) ========= 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.9ACDna87sY/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.H7OztSppXh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005109 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:51:24.120046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 18:51:24.120473) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 18:51:24.121157) ========= ========= Total master_lag: 0.20 =========