builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0224 starttime: 1477667786.68 results: success (0) buildid: 20161028061458 builduid: cb8c03018e444ac3bbc1816141639458 revision: 1561c917ee27c3ea04bd69467e5b8c7c08102f2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.676543) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.677053) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.677335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.703984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.704367) ========= 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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-28 08:16:26-- 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% 25.5M=0s 2016-10-28 08:16:26 (25.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123127 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.847612) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.850987) ========= 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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.025675 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.899685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:26.900053) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1561c917ee27c3ea04bd69467e5b8c7c08102f2a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1561c917ee27c3ea04bd69467e5b8c7c08102f2a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-28 08:16:26,982 truncating revision to first 12 chars 2016-10-28 08:16:26,982 Setting DEBUG logging. 2016-10-28 08:16:26,982 attempt 1/10 2016-10-28 08:16:26,982 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1561c917ee27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-28 08:16:27,682 unpacking tar archive at: mozilla-central-1561c917ee27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:27.883622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:27.887039) ========= script_repo_revision: 1561c917ee27c3ea04bd69467e5b8c7c08102f2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:27.887433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:27.887774) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 08:16:27.907322) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-28 08:16:27.907765) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:16:28 INFO - MultiFileLogger online at 20161028 08:16:28 in /builds/slave/test 08:16:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:16:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:16:28 INFO - {'append_to_log': False, 08:16:28 INFO - 'base_work_dir': '/builds/slave/test', 08:16:28 INFO - 'blob_upload_branch': 'Firefox', 08:16:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:16:28 INFO - 'branch': 'Firefox', 08:16:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:16:28 INFO - 'config_files': ('talos/mac_config.py',), 08:16:28 INFO - 'default_actions': ('clobber', 08:16:28 INFO - 'read-buildbot-config', 08:16:28 INFO - 'download-and-extract', 08:16:28 INFO - 'populate-webroot', 08:16:28 INFO - 'create-virtualenv', 08:16:28 INFO - 'install', 08:16:28 INFO - 'run-tests'), 08:16:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:16:28 INFO - 'download_minidump_stackwalk': True, 08:16:28 INFO - 'download_symbols': 'ondemand', 08:16:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:16:28 INFO - 'tooltool.py': '/tools/tooltool.py', 08:16:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:16:28 INFO - '/tools/misc-python/virtualenv.py')}, 08:16:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:16:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:16:28 INFO - 'installer_path': 'installer.exe', 08:16:28 INFO - 'log_level': 'info', 08:16:28 INFO - 'log_name': 'talos', 08:16:28 INFO - 'log_to_console': True, 08:16:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:16:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:16:28 INFO - 'opt_config_files': (), 08:16:28 INFO - 'pip_index': False, 08:16:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:16:28 INFO - 'cmd': ('bash', 08:16:28 INFO - '-c', 08:16:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:16:28 INFO - 'enabled': True, 08:16:28 INFO - 'halt_on_failure': False, 08:16:28 INFO - 'name': 'check_screen_resolution'},), 08:16:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:16:28 INFO - 'cmd': ('bash', 08:16:28 INFO - '-c', 08:16:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:16:28 INFO - 'enabled': True, 08:16:28 INFO - 'halt_on_failure': False, 08:16:28 INFO - 'name': 'check_screen_resolution'},), 08:16:28 INFO - 'run_cmd_checks_enabled': True, 08:16:28 INFO - 'sps_profile': False, 08:16:28 INFO - 'sps_profile_interval': 0, 08:16:28 INFO - 'suite': 'dromaeojs-e10s', 08:16:28 INFO - 'system_bits': '32', 08:16:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:16:28 INFO - 'title': 't-yosemite-r7-0224', 08:16:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:16:28 INFO - 'use_talos_json': True, 08:16:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:16:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:16:28 INFO - 'work_dir': 'build'} 08:16:28 INFO - [mozharness: 2016-10-28 15:16:28.103442Z] Running clobber step. 08:16:28 INFO - Running pre-action listener: _resource_record_pre_action 08:16:28 INFO - Running main action method: clobber 08:16:28 INFO - rmtree: /builds/slave/test/build 08:16:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:16:30 INFO - Running post-action listener: _resource_record_post_action 08:16:30 INFO - [mozharness: 2016-10-28 15:16:30.101608Z] Finished clobber step (success) 08:16:30 INFO - [mozharness: 2016-10-28 15:16:30.101742Z] Running read-buildbot-config step. 08:16:30 INFO - Running pre-action listener: _resource_record_pre_action 08:16:30 INFO - Running main action method: read_buildbot_config 08:16:30 INFO - Using buildbot properties: 08:16:30 INFO - { 08:16:30 INFO - "project": "", 08:16:30 INFO - "product": "firefox", 08:16:30 INFO - "script_repo_revision": "production", 08:16:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:16:30 INFO - "repository": "", 08:16:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:16:30 INFO - "buildid": "20161028061458", 08:16:30 INFO - "pgo_build": "False", 08:16:30 INFO - "basedir": "/builds/slave/test", 08:16:30 INFO - "buildnumber": 155, 08:16:30 INFO - "slavename": "t-yosemite-r7-0224", 08:16:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:16:30 INFO - "platform": "yosemite_r7", 08:16:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:16:30 INFO - "branch": "mozilla-central", 08:16:30 INFO - "repo_path": "mozilla-central", 08:16:30 INFO - "revision": "1561c917ee27c3ea04bd69467e5b8c7c08102f2a", 08:16:30 INFO - "stage_platform": "macosx64", 08:16:30 INFO - "builduid": "cb8c03018e444ac3bbc1816141639458", 08:16:30 INFO - "slavebuilddir": "test" 08:16:30 INFO - } 08:16:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:16:30 INFO - Running post-action listener: _resource_record_post_action 08:16:30 INFO - [mozharness: 2016-10-28 15:16:30.105081Z] Finished read-buildbot-config step (success) 08:16:30 INFO - [mozharness: 2016-10-28 15:16:30.105194Z] Running download-and-extract step. 08:16:30 INFO - Running pre-action listener: _resource_record_pre_action 08:16:30 INFO - Running main action method: download_and_extract 08:16:30 INFO - mkdir: /builds/slave/test/build/tests 08:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:30 INFO - trying https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:16:30 INFO - Downloading https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:16:30 INFO - Downloaded 1199 bytes. 08:16:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:16:30 INFO - Using the following test package requirements: 08:16:30 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:16:30 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:16:30 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:16:30 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'jsshell-mac.zip'], 08:16:30 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:16:30 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:16:30 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:16:30 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:16:30 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:16:30 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:16:30 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:16:30 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:16:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:16:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:30 INFO - Fetch https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:16:31 INFO - Http code: 200 08:16:31 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 08:16:31 INFO - x-amz-cf-id: gFK6csLbg6A1WS78Y2wJ-qB7z04ZUt1pJoaOU_5lZxgtepBpMtE6ag== 08:16:31 INFO - x-amz-version-id: xn4v_Ah3DlWIMFPM1TL7Ya84D4O2vwsx 08:16:31 INFO - Expected file size: 16377173 08:16:31 INFO - Obtained file size: 16377173 08:16:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:16:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:16:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:38 INFO - Fetch https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:16:38 INFO - Http code: 200 08:16:38 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:16:38 INFO - x-amz-cf-id: Tm-EhI6q6fM-wJyRbmhYVwFy9LeJlzvVqSVAnrV0AQgsevpHNS3qgw== 08:16:38 INFO - x-amz-version-id: xn4v_Ah3DlWIMFPM1TL7Ya84D4O2vwsx 08:16:39 INFO - Expected file size: 16377173 08:16:39 INFO - Obtained file size: 16377173 08:16:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:16:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:16:45 INFO - Fetch https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:16:46 INFO - Http code: 200 08:16:46 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:16:46 INFO - x-amz-cf-id: mj4Wjn5KKX9wWQE0Mbi0MO8FEVqTv0BTT45xZpoz2GzGxAgQCJwI0A== 08:16:46 INFO - x-amz-version-id: VEZVH.vLoQ6a0beCL9ttnAQDDwMdCQPO 08:16:46 INFO - Expected file size: 14409028 08:16:46 INFO - Obtained file size: 14409028 08:16: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')]} 08:16:46 INFO - trying https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:16:46 INFO - Downloading https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:16:49 INFO - Downloaded 111522851 bytes. 08:16:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:16:49 INFO - mkdir: /builds/slave/test/properties 08:16:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:49 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:49 INFO - Contents: 08:16:49 INFO - build_url:https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:16:50 INFO - Running post-action listener: _resource_record_post_action 08:16:50 INFO - Running post-action listener: set_extra_try_arguments 08:16:50 INFO - [mozharness: 2016-10-28 15:16:50.390888Z] Finished download-and-extract step (success) 08:16:50 INFO - [mozharness: 2016-10-28 15:16:50.391012Z] Running populate-webroot step. 08:16:50 INFO - Running pre-action listener: _resource_record_pre_action 08:16:50 INFO - Running main action method: populate_webroot 08:16:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:50 INFO - '--tppagecycles', 08:16:50 INFO - '7'], 08:16:50 INFO - 'tests': ['tsvgm']}}, 08:16:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['damp', 'tps']}, 08:16:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'tests': ['damp', 'tps']}, 08:16:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['dromaeo_dom']}, 08:16:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['basic_compositor_video']}, 08:16:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:16:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['a11yr', 08:16:50 INFO - 'ts_paint', 08:16:50 INFO - 'tpaint', 08:16:50 INFO - 'sessionrestore', 08:16:50 INFO - 'sessionrestore_no_auto_restore', 08:16:50 INFO - 'tabpaint']}, 08:16:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['tsvgx', 08:16:50 INFO - 'tsvgr_opacity', 08:16:50 INFO - 'tart', 08:16:50 INFO - 'tscrollx', 08:16:50 INFO - 'cart']}, 08:16:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:50 INFO - 'tsvgr_opacity', 08:16:50 INFO - 'tart', 08:16:50 INFO - 'tscrollx', 08:16:50 INFO - 'cart']}, 08:16:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'talos_options': ['--disable-e10s'], 08:16:50 INFO - 'tests': ['tp5o']}, 08:16:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'tests': ['tp5o']}, 08:16:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'talos_options': ['--disable-e10s', 08:16:50 INFO - '--xperf_path', 08:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:50 INFO - 'tests': ['tp5n']}, 08:16:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:50 INFO - 'talos_options': ['--xperf_path', 08:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:50 INFO - 'tests': ['tp5n']}}, 08:16:50 INFO - 'talos.zip': {'path': '', 08:16:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:50 INFO - Running post-action listener: _resource_record_post_action 08:16:50 INFO - [mozharness: 2016-10-28 15:16:50.410410Z] Finished populate-webroot step (success) 08:16:50 INFO - [mozharness: 2016-10-28 15:16:50.410536Z] Running create-virtualenv step. 08:16:50 INFO - Running pre-action listener: _resource_record_pre_action 08:16:50 INFO - Running main action method: create_virtualenv 08:16:50 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:50 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 08:16:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:50 INFO - Using real prefix '/tools/python27' 08:16:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:51 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:54 INFO - Installing pip.................done. 08:16:54 INFO - Return code: 0 08:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:16:54 INFO - Reading from file tmpfile_stdout 08:16:54 INFO - Output received: 08:16:54 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) 08:16:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:54 INFO - 'HOME': '/Users/cltbld', 08:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:54 INFO - 'LOGNAME': 'cltbld', 08:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:54 INFO - 'MOZ_NO_REMOTE': '1', 08:16:54 INFO - 'NO_EM_RESTART': '1', 08:16:54 INFO - 'PAGER': '/bin/cat', 08:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:54 INFO - 'PWD': '/builds/slave/test', 08:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:54 INFO - 'SHELL': '/bin/bash', 08:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:16:54 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:54 INFO - 'USER': 'cltbld', 08:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:54 INFO - 'XPC_FLAGS': '0x0', 08:16:54 INFO - 'XPC_SERVICE_NAME': '0', 08:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:54 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 08:16:54 INFO - Cleaning up... 08:16:54 INFO - Return code: 0 08:16:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:54 INFO - 'HOME': '/Users/cltbld', 08:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:54 INFO - 'LOGNAME': 'cltbld', 08:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:54 INFO - 'MOZ_NO_REMOTE': '1', 08:16:54 INFO - 'NO_EM_RESTART': '1', 08:16:54 INFO - 'PAGER': '/bin/cat', 08:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:54 INFO - 'PWD': '/builds/slave/test', 08:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:54 INFO - 'SHELL': '/bin/bash', 08:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:16:54 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:54 INFO - 'USER': 'cltbld', 08:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:54 INFO - 'XPC_FLAGS': '0x0', 08:16:54 INFO - 'XPC_SERVICE_NAME': '0', 08:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:55 INFO - Downloading/unpacking psutil>=3.1.1 08:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:58 INFO - Installing collected packages: psutil 08:16:58 INFO - Running setup.py install for psutil 08:16:58 INFO - building 'psutil._psutil_osx' extension 08:16: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/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:16:59 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 08:16:59 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 08:16:59 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 08:16:59 INFO - building 'psutil._psutil_posix' extension 08:16:59 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 08:16:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:59 INFO - ^ 08:16:59 INFO - 1 warning generated. 08:16:59 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 08:16:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:59 INFO - Successfully installed psutil 08:16:59 INFO - Cleaning up... 08:16:59 INFO - Return code: 0 08:16:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:59 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')]} 08:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:59 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')]} 08:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:16:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:59 INFO - 'HOME': '/Users/cltbld', 08:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:59 INFO - 'LOGNAME': 'cltbld', 08:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:59 INFO - 'MOZ_NO_REMOTE': '1', 08:16:59 INFO - 'NO_EM_RESTART': '1', 08:16:59 INFO - 'PAGER': '/bin/cat', 08:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:59 INFO - 'PWD': '/builds/slave/test', 08:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:59 INFO - 'SHELL': '/bin/bash', 08:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:16:59 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:59 INFO - 'USER': 'cltbld', 08:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:59 INFO - 'XPC_FLAGS': '0x0', 08:16:59 INFO - 'XPC_SERVICE_NAME': '0', 08:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:17:00 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 08:17:00 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 08:17:00 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 08:17:00 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 08:17:00 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 08:17:00 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 08:17:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:17:02 INFO - Installing collected packages: mozsystemmonitor 08:17:02 INFO - Running setup.py install for mozsystemmonitor 08:17:02 INFO - Successfully installed mozsystemmonitor 08:17:02 INFO - Cleaning up... 08:17:02 INFO - Return code: 0 08:17:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:02 INFO - 'HOME': '/Users/cltbld', 08:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:02 INFO - 'LOGNAME': 'cltbld', 08:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:02 INFO - 'MOZ_NO_REMOTE': '1', 08:17:02 INFO - 'NO_EM_RESTART': '1', 08:17:02 INFO - 'PAGER': '/bin/cat', 08:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:02 INFO - 'PWD': '/builds/slave/test', 08:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:02 INFO - 'SHELL': '/bin/bash', 08:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:02 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:02 INFO - 'USER': 'cltbld', 08:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:02 INFO - 'XPC_FLAGS': '0x0', 08:17:02 INFO - 'XPC_SERVICE_NAME': '0', 08:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:03 INFO - Downloading/unpacking jsonschema==2.5.1 08:17:03 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 08:17:03 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 08:17:03 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 08:17:03 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 08:17:03 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 08:17:03 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 08:17:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:17:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:06 INFO - Downloading functools32-3.2.3-2.tar.gz 08:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:17:06 INFO - warning: no files found matching '*.txt' 08:17:06 INFO - no previously-included directories found matching 'build' 08:17:06 INFO - no previously-included directories found matching 'dist' 08:17:06 INFO - no previously-included directories found matching '.git*' 08:17:06 INFO - Installing collected packages: jsonschema, functools32 08:17:06 INFO - Running setup.py install for functools32 08:17:06 INFO - warning: no files found matching '*.txt' 08:17:06 INFO - no previously-included directories found matching 'build' 08:17:06 INFO - no previously-included directories found matching 'dist' 08:17:06 INFO - no previously-included directories found matching '.git*' 08:17:06 INFO - Successfully installed jsonschema functools32 08:17:06 INFO - Cleaning up... 08:17:06 INFO - Return code: 0 08:17:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:17:06 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')]} 08:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:06 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')]} 08:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:06 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:06 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 08:17:06 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 08:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:06 INFO - 'HOME': '/Users/cltbld', 08:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:06 INFO - 'LOGNAME': 'cltbld', 08:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:06 INFO - 'MOZ_NO_REMOTE': '1', 08:17:06 INFO - 'NO_EM_RESTART': '1', 08:17:06 INFO - 'PAGER': '/bin/cat', 08:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:06 INFO - 'PWD': '/builds/slave/test', 08:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:06 INFO - 'SHELL': '/bin/bash', 08:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:06 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:06 INFO - 'USER': 'cltbld', 08:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:06 INFO - 'XPC_FLAGS': '0x0', 08:17:06 INFO - 'XPC_SERVICE_NAME': '0', 08:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:17:06 INFO - Cleaning up... 08:17:06 INFO - Return code: 0 08:17:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:17:06 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')]} 08:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:06 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')]} 08:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:06 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:06 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 08:17:06 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 08:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:06 INFO - 'HOME': '/Users/cltbld', 08:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:06 INFO - 'LOGNAME': 'cltbld', 08:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:06 INFO - 'MOZ_NO_REMOTE': '1', 08:17:06 INFO - 'NO_EM_RESTART': '1', 08:17:06 INFO - 'PAGER': '/bin/cat', 08:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:06 INFO - 'PWD': '/builds/slave/test', 08:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:06 INFO - 'SHELL': '/bin/bash', 08:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:06 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:06 INFO - 'USER': 'cltbld', 08:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:06 INFO - 'XPC_FLAGS': '0x0', 08:17:06 INFO - 'XPC_SERVICE_NAME': '0', 08:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:06 INFO - Downloading/unpacking blobuploader==1.2.4 08:17:06 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 08:17:06 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 08:17:06 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 08:17:06 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 08:17:06 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 08:17:06 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 08:17:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:17:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:17:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:17:10 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 08:17:10 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 08:17:10 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 08:17:10 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 08:17:10 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 08:17:10 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 08:17:10 INFO - Downloading docopt-0.6.1.tar.gz 08:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:17:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:17:10 INFO - Running setup.py install for blobuploader 08:17:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:17:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:17:10 INFO - Running setup.py install for requests 08:17:11 INFO - Running setup.py install for docopt 08:17:11 INFO - Successfully installed blobuploader requests docopt 08:17:11 INFO - Cleaning up... 08:17:11 INFO - Return code: 0 08:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:11 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:11 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 08:17:11 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 08:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:11 INFO - 'HOME': '/Users/cltbld', 08:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:11 INFO - 'LOGNAME': 'cltbld', 08:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:11 INFO - 'MOZ_NO_REMOTE': '1', 08:17:11 INFO - 'NO_EM_RESTART': '1', 08:17:11 INFO - 'PAGER': '/bin/cat', 08:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:11 INFO - 'PWD': '/builds/slave/test', 08:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:11 INFO - 'SHELL': '/bin/bash', 08:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:11 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:11 INFO - 'USER': 'cltbld', 08:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:11 INFO - 'XPC_FLAGS': '0x0', 08:17:11 INFO - 'XPC_SERVICE_NAME': '0', 08:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:11 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-3FGQiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-13IXhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ZBoQPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-aDIsvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RuWOAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7NEXuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-CM7q3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ILCf4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-8PZ8Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-cxRn6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:12 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-s8smWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-2OFyck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-kWKwUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-bHjuhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-KeSweX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-WPKwK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:13 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-0PBsdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:17:13 INFO - Running setup.py install for manifestparser 08:17:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:17:13 INFO - Running setup.py install for mozcrash 08:17:14 INFO - Running setup.py install for mozdebug 08:17:14 INFO - Running setup.py install for mozdevice 08:17:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Running setup.py install for mozfile 08:17:14 INFO - Running setup.py install for mozhttpd 08:17:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Running setup.py install for mozinfo 08:17:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Running setup.py install for mozInstall 08:17:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:17:14 INFO - Running setup.py install for mozleak 08:17:15 INFO - Running setup.py install for mozlog 08:17:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Running setup.py install for moznetwork 08:17:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Running setup.py install for mozprocess 08:17:15 INFO - Running setup.py install for mozprofile 08:17:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Running setup.py install for mozrunner 08:17:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:17:15 INFO - Running setup.py install for mozscreenshot 08:17:16 INFO - Running setup.py install for moztest 08:17:16 INFO - Running setup.py install for mozversion 08:17:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:17:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:17:16 INFO - Cleaning up... 08:17:16 INFO - Return code: 0 08:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:16 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:16 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 08:17:16 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 08:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:16 INFO - 'HOME': '/Users/cltbld', 08:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:16 INFO - 'LOGNAME': 'cltbld', 08:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:16 INFO - 'MOZ_NO_REMOTE': '1', 08:17:16 INFO - 'NO_EM_RESTART': '1', 08:17:16 INFO - 'PAGER': '/bin/cat', 08:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:16 INFO - 'PWD': '/builds/slave/test', 08:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:16 INFO - 'SHELL': '/bin/bash', 08:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:16 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:16 INFO - 'USER': 'cltbld', 08:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:16 INFO - 'XPC_FLAGS': '0x0', 08:17:16 INFO - 'XPC_SERVICE_NAME': '0', 08:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:16 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-1IFpvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17:16 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)) 08:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:16 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-aV_kIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 08:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:16 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-9pEzAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17:16 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)) 08:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:16 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-JvvJ38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-p33aMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-d8w4do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-FrwgIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-PkSOCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-LLCSX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pUAnSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-xBxl0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-8toNnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-6sjvgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17:17 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)) 08:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Q1r7kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-GQV7fT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:18 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)) 08:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-zhQUoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-In5z3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17:18 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)) 08:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:17:18 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)) 08:17:18 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)) 08:17:18 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)) 08:17:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:17:18 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 08:17:18 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 08:17:18 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 08:17:18 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 08:17:18 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 08:17:18 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 08:17:20 INFO - Downloading blessings-1.6.tar.gz 08:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:17:20 INFO - Installing collected packages: blessings 08:17:20 INFO - Running setup.py install for blessings 08:17:21 INFO - Successfully installed blessings 08:17:21 INFO - Cleaning up... 08:17:21 INFO - Return code: 0 08:17:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:21 INFO - Reading from file tmpfile_stdout 08:17:21 INFO - Current package versions: 08:17:21 INFO - blessings == 1.6 08:17:21 INFO - blobuploader == 1.2.4 08:17:21 INFO - docopt == 0.6.1 08:17:21 INFO - functools32 == 3.2.3-2 08:17:21 INFO - jsonschema == 2.5.1 08:17:21 INFO - manifestparser == 1.1 08:17:21 INFO - mozInstall == 1.12 08:17:21 INFO - mozcrash == 0.17 08:17:21 INFO - mozdebug == 0.1 08:17:21 INFO - mozdevice == 0.48 08:17:21 INFO - mozfile == 1.2 08:17:21 INFO - mozhttpd == 0.7 08:17:21 INFO - mozinfo == 0.9 08:17:21 INFO - mozleak == 0.1 08:17:21 INFO - mozlog == 3.4 08:17:21 INFO - moznetwork == 0.27 08:17:21 INFO - mozprocess == 0.23 08:17:21 INFO - mozprofile == 0.28 08:17:21 INFO - mozrunner == 6.12 08:17:21 INFO - mozscreenshot == 0.1 08:17:21 INFO - mozsystemmonitor == 0.3 08:17:21 INFO - moztest == 0.7 08:17:21 INFO - mozversion == 1.4 08:17:21 INFO - psutil == 3.1.1 08:17:21 INFO - requests == 1.2.3 08:17:21 INFO - wsgiref == 0.1.2 08:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:22 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:22 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 08:17:22 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 08:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:22 INFO - 'HOME': '/Users/cltbld', 08:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:22 INFO - 'LOGNAME': 'cltbld', 08:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:22 INFO - 'MOZ_NO_REMOTE': '1', 08:17:22 INFO - 'NO_EM_RESTART': '1', 08:17:22 INFO - 'PAGER': '/bin/cat', 08:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:22 INFO - 'PWD': '/builds/slave/test', 08:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:22 INFO - 'SHELL': '/bin/bash', 08:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:22 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:22 INFO - 'USER': 'cltbld', 08:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:22 INFO - 'XPC_FLAGS': '0x0', 08:17:22 INFO - 'XPC_SERVICE_NAME': '0', 08:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 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)) 08:17:22 INFO - Cleaning up... 08:17:22 INFO - Return code: 0 08:17:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:17:22 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')]} 08:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:22 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')]} 08:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:22 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 0x10bf06490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be7c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1ad9cc30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, '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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 08:17:22 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 08:17:22 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 08:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:22 INFO - 'HOME': '/Users/cltbld', 08:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:22 INFO - 'LOGNAME': 'cltbld', 08:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:22 INFO - 'MOZ_NO_REMOTE': '1', 08:17:22 INFO - 'NO_EM_RESTART': '1', 08:17:22 INFO - 'PAGER': '/bin/cat', 08:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:22 INFO - 'PWD': '/builds/slave/test', 08:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:22 INFO - 'SHELL': '/bin/bash', 08:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:22 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:22 INFO - 'USER': 'cltbld', 08:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:22 INFO - 'XPC_FLAGS': '0x0', 08:17:22 INFO - 'XPC_SERVICE_NAME': '0', 08:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:17:23 INFO - Cleaning up... 08:17:23 INFO - Return code: 0 08:17:23 INFO - Running post-action listener: _resource_record_post_action 08:17:23 INFO - Running post-action listener: _start_resource_monitoring 08:17:23 INFO - Starting resource monitoring. 08:17:23 INFO - [mozharness: 2016-10-28 15:17:23.234187Z] Finished create-virtualenv step (success) 08:17:23 INFO - [mozharness: 2016-10-28 15:17:23.235257Z] Running install step. 08:17:23 INFO - Running pre-action listener: _resource_record_pre_action 08:17:23 INFO - Running main action method: install 08:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:23 INFO - Reading from file tmpfile_stdout 08:17:23 INFO - Detecting whether we're running mozinstall >=1.0... 08:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:17:23 INFO - Reading from file tmpfile_stdout 08:17:23 INFO - Output received: 08:17:23 INFO - Usage: mozinstall [options] installer 08:17:23 INFO - Options: 08:17:23 INFO - -h, --help show this help message and exit 08:17:23 INFO - -d DEST, --destination=DEST 08:17:23 INFO - Directory to install application into. [default: 08:17:23 INFO - "/builds/slave/test"] 08:17:23 INFO - --app=APP Application being installed. [default: firefox] 08:17:23 INFO - mkdir: /builds/slave/test/build/application 08:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:17:58 INFO - Reading from file tmpfile_stdout 08:17:58 INFO - Output received: 08:17:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:17:58 INFO - Running post-action listener: _resource_record_post_action 08:17:58 INFO - [mozharness: 2016-10-28 15:17:58.334399Z] Finished install step (success) 08:17:58 INFO - [mozharness: 2016-10-28 15:17:58.334554Z] Running run-tests step. 08:17:58 INFO - Running pre-action listener: _resource_record_pre_action 08:17:58 INFO - Running main action method: run_tests 08:17:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:58 INFO - 2016-10-28 08:17:58.348 screenresolution[1637:7449] starting screenresolution argv=screenresolution get 08:17:58 INFO - 2016-10-28 08:17:58.351 screenresolution[1637:7449] Display 0: 1600x1200x32@60 08:17:58 INFO - 2016-10-28 08:17:58.357 screenresolution[1638:7452] starting screenresolution argv=screenresolution list 08:17:58 INFO - Available Modes on Display 0 08:17:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:58 INFO - Intel Iris: 08:17:58 INFO - Chipset Model: Intel Iris 08:17:58 INFO - Type: GPU 08:17:58 INFO - Bus: Built-In 08:17:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:58 INFO - Vendor: Intel (0x8086) 08:17:58 INFO - Device ID: 0x0a2e 08:17:58 INFO - Revision ID: 0x0009 08:17:58 INFO - Displays: 08:17:58 INFO - Display: 08:17:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:58 INFO - Display Serial Number: 1600x1200 60 08:17:58 INFO - Main Display: Yes 08:17:58 INFO - Mirror: Off 08:17:58 INFO - Online: Yes 08:17:58 INFO - Rotation: Supported 08:17:58 INFO - Return code: 0 08:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:17:58 INFO - Python 2.7.3 08:17:58 INFO - Return code: 0 08:17:58 INFO - grabbing minidump binary from tooltool 08:17: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')]} 08:17:58 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 0x10bd1ad30>, '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 0x10bb40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd12b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:17:58 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 08:17:58 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 08:17:58 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 08:17:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:17:58 INFO - Return code: 0 08:17:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:17:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:17:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:17:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:17:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 08:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0224 --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 08:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxhtmXD3YQ/Render', 08:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:58 INFO - 'HOME': '/Users/cltbld', 08:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:58 INFO - 'LOGNAME': 'cltbld', 08:17:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:17:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:58 INFO - 'MOZ_NO_REMOTE': '1', 08:17:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:17:58 INFO - 'NO_EM_RESTART': '1', 08:17:58 INFO - 'PAGER': '/bin/cat', 08:17:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:58 INFO - 'PWD': '/builds/slave/test', 08:17:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:58 INFO - 'SHELL': '/bin/bash', 08:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6AgHDrHmLX/Listeners', 08:17:58 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 08:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:58 INFO - 'USER': 'cltbld', 08:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:58 INFO - 'XPC_FLAGS': '0x0', 08:17:58 INFO - 'XPC_SERVICE_NAME': '0', 08:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 08:17:58 INFO - mozversion application_buildid: 20161028061458 08:17:58 INFO - mozversion application_changeset: 1561c917ee27c3ea04bd69467e5b8c7c08102f2a 08:17:58 INFO - mozversion application_display_name: Nightly 08:17:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:17:58 INFO - mozversion application_name: Firefox 08:17:58 INFO - mozversion application_remotingname: firefox 08:17:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:17:58 INFO - mozversion application_vendor: Mozilla 08:17:58 INFO - mozversion application_version: 52.0a1 08:17:58 INFO - mozversion platform_buildid: 20161028061458 08:17:58 INFO - mozversion platform_changeset: 1561c917ee27c3ea04bd69467e5b8c7c08102f2a 08:17:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:17:58 INFO - mozversion platform_version: 52.0a1 08:17:58 INFO - using testdate: 1477667878 08:17:58 INFO - actual date: 1477667878 08:17:58 INFO - starting webserver on 'localhost:49280' 08:17:58 INFO - SUITE-START | Running 2 tests 08:17:58 INFO - TEST-START | dromaeo_css 08:17:58 INFO - operating with platform_type : mac_ 08:17:58 INFO - Initialising browser for dromaeo_css test... 08:17:58 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpZfaPKo/profile http://localhost:49280/getInfo.html) 08:18:01 INFO - PROCESS | 1648 | 2016-10-28 08:18:01.712 plugin-container[1650:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 08:18:01 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:18:01 INFO - PROCESS | 1648 | 2016-10-28 08:18:01.717 plugin-container[1650:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 08:18:01 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:18:01 INFO - PROCESS | 1648 | 2016-10-28 08:18:01.717 plugin-container[1650:7609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:18:01 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 08:18:01 INFO - PROCESS | 1648 | colorDepth:24 08:18:01 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 08:18:01 INFO - PROCESS | 1648 | __metrics 08:18:01 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 08:18:02 INFO - TEST-INFO | 1648: exit 0 08:18:02 INFO - Browser initialized. 08:18:02 INFO - Running cycle 1/1 for dromaeo_css test... 08:18:02 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpZfaPKo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:18:04 INFO - PROCESS | 1651 | 2016-10-28 08:18:04.977 plugin-container[1655:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 08:18:04 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:18:04 INFO - PROCESS | 1651 | 2016-10-28 08:18:04.977 plugin-container[1655:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 08:18:04 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:18:04 INFO - PROCESS | 1651 | 2016-10-28 08:18:04.977 plugin-container[1655:7781] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:18:14 INFO - PROCESS | 1651 | RSS: Main: 236429312 08:18:14 INFO - PROCESS | 1651 | 08:19:04 INFO - PROCESS | 1651 | 1477667944214 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:21:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 08:21:19 INFO - PROCESS | 1651 | RSS: Main: 250707968 08:21:19 INFO - PROCESS | 1651 | 08:24:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 08:24:22 INFO - PROCESS | 1651 | RSS: Main: 261169152 08:24:22 INFO - PROCESS | 1651 | 08:27:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 08:27:25 INFO - PROCESS | 1651 | RSS: Main: 266805248 08:27:25 INFO - PROCESS | 1651 | 08:30:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 08:30:27 INFO - PROCESS | 1651 | RSS: Main: 265338880 08:30:27 INFO - PROCESS | 1651 | 08:33:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 08:33:31 INFO - PROCESS | 1651 | RSS: Main: 270065664 08:33:31 INFO - PROCESS | 1651 | 08:36:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 08:36:34 INFO - PROCESS | 1651 | RSS: Main: 272248832 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | __start_tp_report 08:36:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 08:36:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 08:36:34 INFO - PROCESS | 1651 | |i|pagename|runs| 08:36:34 INFO - PROCESS | 1651 | |0;dojo.html;3358.781679190854;3395.864165433383;3412.266362732012;3416.1801167719746;3435.4159792835217;5585.218069470267;5667.773289068437;5668.206451096845;5719.256496655429;5784.68184249866;5602.635828671134;5730.541556675466;5731.254936858202;5765.365809760923;5867.677277749733;5164.967006598696;5224.085784987631;5242.030224408478;5252.579793616502;5336.38631557372;5229.111051121299;5291.814786482474;5332.200169974515;5350.35795704516;5389.380221274545;5087;5193.714345710984;5256.448072952323;5279.1817268323475;5285.709285989269;5237.13587258102;5248.895022099537;5261.500157485033;5264.631475796696;5288.629795914288;5198.636095473318;5200.609954253408;5207.1668533034535;5226.97386513065;5255.921161182585;5366.597505187087;5530.391656917735;5618.438156184389;5660.179274005246;5758.337791154029;6110.072772100023;6159.568830181889;6203.193584833988;6209.3790620938;6244.56288059836;6174;6212.844678883019;6214.067889816519;6215.192025036717;6218.160548326008;5641.661500309994;5690.601657883991;5853.824385268449;5863.9969439745555;5915.526757859361;6162.26052873649;6169;6180.042093475518;6223.875522489616;6294.968525157345;6097.908531372033;6154.569180157436;6166.845828854314;6188.319284878659;6196.566240363221;6069.575129740876;6151.784687535915;6191.318954914956;6220.502359811204;6240.843978900473;5835.299764028344;6106.82471942484;6131.908021379683;6154.83058218937;6196.659183744938;6193.225846769154;6219.968900155561;6229.0967809667345;6234.688265586702;6247.3440288769025;4919.877003074952;4945.876353091129;4947.134251505972;4952.356193694796;4971.6271279654175;4304.117655880537;4351.891202719894;4364.083542456056;4404.603585677304;4417.690761646654;4784.832530861403;5835.591508594358;5865;5905.232319798484;5912.3496415394275;6185.41238582343;6219.751209951552;6238.937610623926;6252.218472690914;6254.8123556293385;4996.825111121093;5390.353157621111;5429.457054294539;5834.883302333929;5851.1515830205235;5437.918431223535;5439.48324909133;5447.101228297366;5480.177973304037;5503.889922201613;5251.684898906078;6347.238331400262;6394.296627370893;6402.35976402356;6404.103425520431;6108.1903020305945;6128.228138398679;6142.955697531341;6181.3200547938795;6224.533160013108;6288.182536270256;6411.807645770635;6431;6445.387688169616;6446.323136070645;5180.870478238075;6012.308584512837;6198.535109866688;6224.190855188796;6236.688165591793;6075.574709770451;6139.456205378207;6146.754129834757;6208.379162083756;6231.532635052298;6087;6107.389261073857;6126.792505495216;6139.461473530676;6146.661933593696;5933.643981361132;6099.207103076572;6161.907571386522;6163.260408750979;6173.722182501709;5727.541796656341;5904.143899134688;5928.762849485972;5956.583039187215;6052.001419765689;5850.7713380190635;6055.6063855849225;6078.969605151946;6113.749968913837;6126.23422072241;5210;5994.790182343597;6035.1249068885645;6072.180255665428;6076.179715738319 08:36:34 INFO - PROCESS | 1651 | |1;ext.html;471.7499725145672;495.4698472634283;529.1956226535665;538.9272448219492;545.1033050631709;31390.529142062886;31671.524927126113;40613.59386406135;43742.343864842056;43843.71172162093;42396.364054539205;42634;42721.572784272146;42753.78623106888;42784;8096.190380961886;9486.43081415114;9612.702285191515;9613.038696130367;9658.806823863519;9446.480443575601;9508.524573771318;9579.80840383192;9609.759756006086;9615.855762163574;9557;9570;9602.08780165883;9606.087421694929;9610;9523.380980236263;9567.952160239223;9589.280803939733;9590.856137157949;9617.807643847083;9447.52762361886;9535.570899309549;9545;9577.664781732607;9588.95205523975;39128;39209.41185882214;39449.633022018774;39676.40485392721;39915.60084399148;3370.8146051967133;3421.1105112670634;3449.5688038995127;3471.3230919970615;3498.038039539147;30679.846600767076;31046.534301985488;31365.37269254602;31858.522122168186;31896.20259493508;3021.8489075546354;3153.3693261347594;3175.888843890476;3223.580934478526;3255.430299697567;410092;414727;414863;415332;417380.9130954356;48152.759236203594;50524.231651892005;51104.74447627738;56997.430025699214;59268.70365648231;61858;62356.68821655863;62617.68691156515;62663.373366265754;63131.684341577995;15905;15949.760753588705;16122.516324510283;16149;16196;12013.399330033464;12026.458809353426;12452;12514.87485125137;12518.06114541413;12113.878861211451;15903.375942969511;15927.522174334788;16005.599860003593;16142.919285403732;3190.3140824722796;6010;6278.780242691484;6283.151774510474;6313.390085528161;869.8086420987372;872.6422166911536;969.5249327829454;970.9223262139012;1013.0780989299702;24501.264962051166;29911.700882990892;30003.69996300009;30203.848980754956;30844.383112337626;1013.5287091502339;1031.6440827914357;1036.9325993810378;1062.1449732964884;1138.4250953268547;13384.19694818314;15717.842821571638;15719.371225150866;15885.761713574306;16166.030038197745;626182;626905;633460;634288;635689;58175.709121454965;58291.70854145787;58580.82838343209;58655.70672146697;58697.70651146802;1333.9132956357805;1376.8829649479906;1385.3350391811987;1393.136255521583;1402.3689339797131;25196.118135865156;29301.85349073241;30071.849640751658;30585.54121688132;30768.230794230323;48597.02805943932;50443.49556504388;50773.23840142327;50848;50982.23526647178;588673;592310;592515;593269;593392;25524.23427297184;26307.868460657573;26446.471070578096;26754.59868102019;26812;42584.78707606442;46586.36947706814;46709.59871203869;47166.05667886692;48511;29769.404611907208;39705.602943971346;40036.399454008795;40245;41069.794651026554;38823.611763882;38898.61101389063;39263.60736392599;40160.397594036694;40248.597514025656;37329.62670373262;38269.043273918374;38468.42297365598;38484.80757596194;41506.79246603748;30390.69609303967;34241;34268.82865585656;34415.82792086023;34493.13767155841;24743.50512989766;24890.875545622155;25573.74426255788;25824.612630810178;25958 08:36:34 INFO - PROCESS | 1651 | |2;jquery.html;336.0020738406932;364.79389145132996;366.92111196092833;375.3187963845622;390.69313147521547;15425.305861236244;19480.805191948093;20127.49681257969;20762.065707043163;21021.579568408662;19754.30859919903;19936.90031549844;20001.099950502226;20390.592188156228;20492.38522844317;5012.172991456406;5726.3700992890745;5812.099124635667;5898.41015898411;6015.6992150392525;5779.595428320019;5978.910316345258;6002.459778619946;6010.789622363218;6024.3674414186535;5655.688937108457;5898.439648233411;5910.231669882909;5918.585699001072;5943.791967281146;5952.523798096142;6016.879662406727;6041.78853740117;6042.486388656991;6046.486048685867;5143.254228136899;5223.6343455958095;5234.18870075139;5458.426865179139;5487.204355368489;12947.482100716054;12968.481260749653;13224.735505289937;15706.607334816721;24058.51882962331;2006.8795872247713;2313.3921924262645;2420.7095148582284;2581.1998280533094;2746.5056289867816;9483.542417091045;9674.90325096747;9951.800963980751;10220;10486.737831554196;1762.7444020617068;1830.2404502131599;1861.6276744650988;1864.766904136983;1876.9249230030782;95319.52340238323;111054;116110.83889161085;119973;120780.39609801894;10701.197410194267;11336.206465547333;11353.829692554618;11373.772524549462;11393.829092563617;14307.070040447337;14336.068155569854;14341;14343.641408964859;14391.424343026161;6861.55399899005;8133.79665508367;8434.650455927022;8537.402381833212;8573;8249.505029698237;8265.090840007715;8280.00639923201;8381.622826972802;8411.747647570583;3578.8905439313376;3652.543432070991;3692.538432695913;4281.978590107092;4561.087782443525;1508.8415716349625;1744.7960906974147;1827.8080801515916;1922.5866438715743;1953.6288105259955;892.3262936482914;899.7030979776658;908.8136931928939;930.223263574909;945.4563625915126;16537.338506459848;19502.90248548748;20127.798722012885;20898.059587318305;21024.053917573747;935.5322338830584;966.2028826218398;1034.5137785241075;1049.4123290957089;1064.1912146768511;10603.89396106029;11027.276847300493;11101.111911047097;11130.888691113147;11135.33188008722;532019;579260;580485;592861;611770;118045;120585.39707301407;121919;127507;134793;955.6989548292265;977.8777652793401;1001.9949900250452;1002.1431675917132;1023.1456733627505;18380.540486487946;19133.425997220107;19167.616647667248;19168.808311916702;19266.71099933474;43145.784271078446;54433.45566544284;54588.72705636446;54747.726261368436;55091;64673.35326646861;80547.19452805397;80570.79143812964;80874;81685.59157204175;7660.5403675779635;7780.649870755717;7969.083555390972;8017;8021.5935219435105;11885;11904.940475297568;11952.071742423163;12347.259164450163;12476.937615311865;8694.739157825275;10087.445190514592;10598.152147828001;10776.568937242422;10821.404822734825;10500.632477863544;10513.002529519366;10639.946800265949;10735.892641073802;10797.758257800138;8904.86642700347;9628.71113866585;9666.371685840399;10991.571095757501;11041.668749937515;7767.883481747891;7885.605719714106;7913.169117242837;7943.801404964922;7957.721479748181;10208.387496750218;10629.840552391875;10821.9458902705;10932.56269749201;10984.340939543654 08:36:34 INFO - PROCESS | 1651 | |3;mootools.html;1534.984650153498;1565.9451919182832;1789.2306308287439;1863.5248011757;1898.4684288399244;7329.083864516935;7552.282533159355;7920.366370690345;8383.748487545376;8384.62269197886;7866.763997080083;8163.632636531355;8178.182181781819;8286.709965151222;8302.95848520758;2219.2676416782506;2267.807236384909;2271.2731925783755;2271.931842044733;2276.0668126068317;2251.234580242717;2260.5026894083276;2261.118163916074;2295.4376177836452;2309.8267629927823;2216.423729830248;2253.256425379621;2264.116994372196;2269.353234328217;2277.7380601230907;2228.699125618045;2232.966505502419;2236.519148383089;2256.4020534558354;2266.0709109265204;2246.4383904023994;2255.22194842779;2279.361778701966;2283.063943783041;2291.2667946257206;8244.258016778458;8274.875876861792;8350;8367.916320836775;8403.41176117672;4169.687273454473;4545.5227051208485;4930.383702037246;5144.871378215537;5145.536901678867;2851.82889026657;3141.513065474855;3483.059573915054;3487.4420092785285;3505.964940350589;833.2792134803412;851.2253848997444;1001.8346972749488;1021.2799976016964;1037.9481025948671;10342.379457232515;10592.411138329313;10655.413952232702;10738.30201036938;10745.731356716145;4349.456317960255;4352.477702675636;4373.737575745466;4398.692091553625;4481.596656300949;8750.256228220545;8764.298856091498;8802.735917922473;8853.026167121612;8892;4869.732164730974;4889.339939108246;4948.307236986825;4982.377202849644;5001.924971125436;3287.3589650018016;4551.158035763395;4576.405067341224;4611.2852507861335;4676.158291507561;3580.3197392495345;3671.7429779915683;3677.1726361568435;3688.151725103188;3738.9439158412647;1052.4474650808386;1197.3713800254936;1208.3173007250875;1209.4617895036624;1219.3415555600054;754.2193829386558;858.5750053723322;910.8906931168302;912.7900582865979;932.072587775168;5001.249812528077;5011.323471331323;5473.713677285835;5495.807646732425;5630.324361076697;754.9509281896659;796.7968168117093;798.9161138080534;885.521818218155;929.1126973740062;3712.424574191005;3801.7338786284695;3824.4836947012354;3833.310004199215;3899.512560929884;8556.802047713325;9593.088656577615;9803.950980245054;9829.803403932025;9959.452230127412;8572.442791218551;9820.312578119463;9936.254780891462;9945;9951.552180151766;6424.486041116793;6727.596344219362;7641.465097443125;7682.1549629540705;7808.648610812527;4711.505291944297;4726.7636618169645;5460.31746031746;5480.177973304037;5548.4174161712435;8906.198442140238;9027.187553120251;9042.728718138467;9150.725478235663;9354.01782812822;9176.862347064933;9189.356745027784;9295.488748118918;9408.81182376335;9454.621815127319;5358.678479291255;6598.538102332791;6867.725290988505;6899.103116594811;7074.115735533059;7728.806779830549;7823.882641760264;7915.010623672041;7920.247576480225;7946.364290856834;7001.719931202899;7104.18301895268;7133.322334378226;7156.427485801228;7258.818529536804;7010.037189586924;7044.330788575077;7047.964760176166;7063.6821343038655;7139.357457828821;6297.590656607306;6326.177596912372;6396.712147953446;6412.198475190601;6419.64691941929;4741.715497070187;4745.406824146981;4791.784369703302;4843.46721860588;4846.660733748745;4676.3686902267755;4688.460827004802;4786.138495070921;4865.70805751656;4973.875653108701 08:36:34 INFO - PROCESS | 1651 | |4;prototype.html;289.178732399984;294.0897920934707;304.90700336397396;306.2283046722259;308.9320349523104;5189.117849965505;5349.438308977559;5365.839024829256;5473.753681084351;5515.283013208283;4849.636277279209;4942.937268487274;5105.978804239148;5246.799452334073;5300.104423248909;3686.2443199144177;3904.609539046087;3988.601139886003;4055.472788537486;4087.3255912774357;3430.2281986552953;3516.1736991806906;3666.7244753858704;4048.1701251243426;4127.793610319487;3300.5214243934615;3410.283840393508;3416.692497675209;3553.271579326245;3871.7676939383587;3500.737444691633;3539.8407071681836;3802.954187598405;4054.7972601369815;4059.97970010151;3355.5302257683943;3546.4218422801864;3546.9536486736474;3555.306715190539;3579.731520136;4604.102200070987;4940.678855874393;5002.574781143607;5287.206918962186;5306.52241298285;3702.8889133326043;4275.166342563201;4291.077418355068;4644.6052085572765;4657.88355291117;5633.338330085437;5635.690037048002;5664.830055098354;5687;5710.685912274823;3531.9293614127832;3784.202503810668;3893.4886997886206;3920.2551515212303;3931.528216613996;5816.156657284712;5847.26909136358;5854.648721076706;5864.824055278348;5878.823635290948;3058.908232753021;3547.609762926076;3635.1457407509215;3801.144742432986;3825.0628595993603;3259.3481303739345;3385.1875549868346;3459.3081383723356;3718.5165928429674;3880.3015457217407;3595.5146055282203;3855.5566109897723;3987.52149742027;4308.418363520947;4423.712458690175;4608.377868987711;4879.731614761222;4913.385826771653;5573;5613.550915926716;1080.0819303591884;1244.8547336450488;1280.3342262023696;1319.920804751718;1324.4635922451423;714.9142102947679;864.9697260595849;883.6862913665685;892.5358813416987;893.1738142218422;5281.392639846466;5285.709285989307;5290.444503327172;5304.655197412156;5305.283786688825;4633.497911128021;4756.476787553366;5079.907819818683;5772.9711351442975;5810.463228867524;4555.703879247838;5220.425753167148;5237.292965449616;5262.368515778131;5304.522592966651;2198.40643026382;2338.5439839231526;2398.8320817542954;2434.379233295498;2573.7426257374113;4487.730736155841;4729.692569983009;5005.4232916631145;5059.089363914531;5089.440161582223;4540.659450541157;4570.908581828345;4593.448786145684;4664.510226426177;5027.371578552681;4423.491298500714;4548.2716567704065;4854.611631069506;5000.599592277286;5085.3134826797905;4205.600467955539;4426.247537918497;4540.341650460731;4656.93014604788;4909.877253068559;4747.489884609566;4811.975940120277;4820.71075735457;4831.4685384608365;4843.757812109451;4642.53574642533;4666.440027196622;4769.57073863354;4843.764839965786;5068.767579071369;3111.424386488507;3144.5754823099505;3248.7563432742168;3342.866285348559;3395.49067639856;4360.234105187405;4711.8350857719815;4824.877054096187;4834.5890599299455;4850.660453768202;4015.017623740547;4135.441715368507;4673.976630116827;4716.858494245178;4761.785719642555;3902.965714085713;4228.640565551963;4342.957690154277;4704.529547045268;4762.880927976828;3910.4329872168946;5254.13306804373;5482.464909825256;5557.833265002057;5625.46558076982;4630.76846157684;5728.598998070095;5741.856453588526;5747.396523365153;5752.864688744728;4779.617630589614;4889.804407823647;4920.852374428773;5437.075697131418;5686.545076393796 08:36:34 INFO - PROCESS | 1651 | |5;yui.html;702.5644100657593;706.1498872952861;722.819295176206;776.1074764021374;823.7199352220244;13169.604911852648;14225.78661320081;14426.855731442682;15076.32156552955;15099.773503397459;13761.724765504683;13846.930765346184;13881.861181388183;13993.93003034986;14145.222012789292;709.7196607340085;877.9253763430116;881.3478026260585;892.9151730585584;893.7229458867772;692.8856738638119;713.1406654980735;865.3466632692308;870.1081391573656;870.6430363550945;906.071276941134;940.3229674634252;956.2254573795248;958.1903291718487;960.4525420510312;936.2556767369957;947.8625599288099;949.8100379924043;950.0594411532533;950.2493030505892;691.9169699636076;698.2982102986466;698.3784431855602;699.8215455058978;699.9300069993011;13886.93056534721;14415.044915792343;14457.060291081047;14572.052816566995;14583;13366.198028118344;13463.326833658375;13471.191728496322;13630.727385452334;13731.519396821062;12072;12172.26966382011;12335.321557314344;12404.875951240552;12501.179403395714;4260.318349064135;4362.825486980517;4396.1427521633295;4459.799309031101;4536.56902594253;83586;83871;84284;84607.57696211542;86184.56907715484;8984;10527.21045921559;10733.946330268298;10802;11185.888141118647;20323.288684895957;20446.182152713725;20751.066202020946;20776.896115519325;20816.687749683766;6754.729810807611;6853.965730171317;6912.101426814482;6965.164180298295;7049.506534542636;3819.809009549567;4210.705250632426;4252.149570085995;4258.87223383299;4274.230638485041;3053.3130045739977;3254.5118232264876;3364.293498365371;3364.6130694970393;3773.716971227114;677.6036018928884;683.8324610470466;691.8650863081751;703.9507234493639;705.6683358821346;557.6542543622899;630.8769789890928;660.4683229999896;674.9696263668146;685.8251145957848;7732.57470839098;8383.245507904317;9168.81662366749;9176.632934682671;9199.77000574991;595.3329085696106;609.3845216331537;616.9352218017133;652.4519403700914;660.725798793505;4323.718958267703;4473.507914129443;4490.236659767847;4526.185286648435;4529.5243999380245;90045;99240.50379748055;102306.3731494775;106079.46960265304;107661.38507922388;69208;94277.5286123565;96881.51559242158;97064;99021;50936.21723239669;57913.420865791646;73034;73165.73268212647;76392.23607763962;8024.307025626884;9671.468069256258;9729;9764.804703906024;9806.362586431858;41371.172576547695;44319.77840110779;52897.735511323735;53146.573486698995;53214.73392633012;32925.68297268082;46985.76507117443;57538.424615753305;73620;76725;12193.32936688455;12265.816012759995;12419.19275247106;12551.811722823983;12819.487220511075;13899.583012509642;14284.71430571362;14361;14474.710505790035;14660.633484162981;13148;13248.602541923758;13252.668683282995;13653.79519307231;13707.451701931812;12753.74492510126;12932.870671293167;13056.34718264102;13096;13599.728005440034;12557;12654.177478463871;12831;12851.357432128543;13125.474981000652;10634.521446534769;10641.787164256517;10786.913358742659;10883.23817332779;10941.398223097805;9065.728028159167;9808.852867206855;11009.83485247738;11233.32600044;11298.661040168809 08:36:34 INFO - PROCESS | 1651 | __end_tp_report 08:36:34 INFO - PROCESS | 1651 | __start_cc_report 08:36:34 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,356 08:36:34 INFO - PROCESS | 1651 | __end_cc_report 08:36:34 INFO - PROCESS | 1651 | __startTimestamp1477668994162__endTimestamp 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | ------- Summary: start ------- 08:36:34 INFO - PROCESS | 1651 | Number of tests: 6 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5721.17 Median:5928.76 stddev:635.75 (10.7%) stddev-sans-first:610.09 08:36:34 INFO - PROCESS | 1651 | Values: 3358.8 3395.9 3412.3 3416.2 3435.4 5585.2 5667.8 5668.2 5719.3 5784.7 5602.6 5730.5 5731.3 5765.4 5867.7 5165.0 5224.1 5242.0 5252.6 5336.4 5229.1 5291.8 5332.2 5350.4 5389.4 5087.0 5193.7 5256.4 5279.2 5285.7 5237.1 5248.9 5261.5 5264.6 5288.6 5198.6 5200.6 5207.2 5227.0 5255.9 5366.6 5530.4 5618.4 5660.2 5758.3 6110.1 6159.6 6203.2 6209.4 6244.6 6174.0 6212.8 6214.1 6215.2 6218.2 5641.7 5690.6 5853.8 5864.0 5915.5 6162.3 6169.0 6180.0 6223.9 6295.0 6097.9 6154.6 6166.8 6188.3 6196.6 6069.6 6151.8 6191.3 6220.5 6240.8 5835.3 6106.8 6131.9 6154.8 6196.7 6193.2 6220.0 6229.1 6234.7 6247.3 4919.9 4945.9 4947.1 4952.4 4971.6 4304.1 4351.9 4364.1 4404.6 4417.7 4784.8 5835.6 5865.0 5905.2 5912.3 6185.4 6219.8 6238.9 6252.2 6254.8 4996.8 5390.4 5429.5 5834.9 5851.2 5437.9 5439.5 5447.1 5480.2 5503.9 5251.7 6347.2 6394.3 6402.4 6404.1 6108.2 6128.2 6143.0 6181.3 6224.5 6288.2 6411.8 6431.0 6445.4 6446.3 5180.9 6012.3 6198.5 6224.2 6236.7 6075.6 6139.5 6146.8 6208.4 6231.5 6087.0 6107.4 6126.8 6139.5 6146.7 5933.6 6099.2 6161.9 6163.3 6173.7 5727.5 5904.1 5928.8 5956.6 6052.0 5850.8 6055.6 6079.0 6113.7 6126.2 5210.0 5994.8 6035.1 6072.2 6076.2 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:67864.36 Median:40246.80 stddev:148163.25 (368.1%) stddev-sans-first:148492.49 08:36:34 INFO - PROCESS | 1651 | Values: 471.7 495.5 529.2 538.9 545.1 31390.5 31671.5 40613.6 43742.3 43843.7 42396.4 42634.0 42721.6 42753.8 42784.0 8096.2 9486.4 9612.7 9613.0 9658.8 9446.5 9508.5 9579.8 9609.8 9615.9 9557.0 9570.0 9602.1 9606.1 9610.0 9523.4 9568.0 9589.3 9590.9 9617.8 9447.5 9535.6 9545.0 9577.7 9589.0 39128.0 39209.4 39449.6 39676.4 39915.6 3370.8 3421.1 3449.6 3471.3 3498.0 30679.8 31046.5 31365.4 31858.5 31896.2 3021.8 3153.4 3175.9 3223.6 3255.4 410092.0 414727.0 414863.0 415332.0 417380.9 48152.8 50524.2 51104.7 56997.4 59268.7 61858.0 62356.7 62617.7 62663.4 63131.7 15905.0 15949.8 16122.5 16149.0 16196.0 12013.4 12026.5 12452.0 12514.9 12518.1 12113.9 15903.4 15927.5 16005.6 16142.9 3190.3 6010.0 6278.8 6283.2 6313.4 869.8 872.6 969.5 970.9 1013.1 24501.3 29911.7 30003.7 30203.8 30844.4 1013.5 1031.6 1036.9 1062.1 1138.4 13384.2 15717.8 15719.4 15885.8 16166.0 626182.0 626905.0 633460.0 634288.0 635689.0 58175.7 58291.7 58580.8 58655.7 58697.7 1333.9 1376.9 1385.3 1393.1 1402.4 25196.1 29301.9 30071.8 30585.5 30768.2 48597.0 50443.5 50773.2 50848.0 50982.2 588673.0 592310.0 592515.0 593269.0 593392.0 25524.2 26307.9 26446.5 26754.6 26812.0 42584.8 46586.4 46709.6 47166.1 48511.0 29769.4 39705.6 40036.4 40245.0 41069.8 38823.6 38898.6 39263.6 40160.4 40248.6 37329.6 38269.0 38468.4 38484.8 41506.8 30390.7 34241.0 34268.8 34415.8 34493.1 24743.5 24890.9 25573.7 25824.6 25958.0 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:33751.51 Median:2500.95 stddev:96851.79 (3872.6%) stddev-sans-first:97090.98 08:36:34 INFO - PROCESS | 1651 | Values: 336.0 364.8 366.9 375.3 390.7 15425.3 19480.8 20127.5 20762.1 21021.6 19754.3 19936.9 20001.1 20390.6 20492.4 5012.2 5726.4 5812.1 5898.4 6015.7 5779.6 5978.9 6002.5 6010.8 6024.4 5655.7 5898.4 5910.2 5918.6 5943.8 5952.5 6016.9 6041.8 6042.5 6046.5 5143.3 5223.6 5234.2 5458.4 5487.2 12947.5 12968.5 13224.7 15706.6 24058.5 2006.9 2313.4 2420.7 2581.2 2746.5 9483.5 9674.9 9951.8 10220.0 10486.7 1762.7 1830.2 1861.6 1864.8 1876.9 95319.5 111054.0 116110.8 119973.0 120780.4 10701.2 11336.2 11353.8 11373.8 11393.8 14307.1 14336.1 14341.0 14343.6 14391.4 6861.6 8133.8 8434.7 8537.4 8573.0 8249.5 8265.1 8280.0 8381.6 8411.7 3578.9 3652.5 3692.5 4282.0 4561.1 1508.8 1744.8 1827.8 1922.6 1953.6 892.3 899.7 908.8 930.2 945.5 16537.3 19502.9 20127.8 20898.1 21024.1 935.5 966.2 1034.5 1049.4 1064.2 10603.9 11027.3 11101.1 11130.9 11135.3 532019.0 579260.0 580485.0 592861.0 611770.0 118045.0 120585.4 121919.0 127507.0 134793.0 955.7 977.9 1002.0 1002.1 1023.1 18380.5 19133.4 19167.6 19168.8 19266.7 43145.8 54433.5 54588.7 54747.7 55091.0 64673.4 80547.2 80570.8 80874.0 81685.6 7660.5 7780.6 7969.1 8017.0 8021.6 11885.0 11904.9 11952.1 12347.3 12476.9 8694.7 10087.4 10598.2 10776.6 10821.4 10500.6 10513.0 10639.9 10735.9 10797.8 8904.9 9628.7 9666.4 10991.6 11041.7 7767.9 7885.6 7913.2 7943.8 7957.7 10208.4 10629.8 10821.9 10932.6 10984.3 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5274.40 Median:5145.20 stddev:2894.64 (56.3%) stddev-sans-first:2889.12 08:36:34 INFO - PROCESS | 1651 | Values: 1535.0 1565.9 1789.2 1863.5 1898.5 7329.1 7552.3 7920.4 8383.7 8384.6 7866.8 8163.6 8178.2 8286.7 8303.0 2219.3 2267.8 2271.3 2271.9 2276.1 2251.2 2260.5 2261.1 2295.4 2309.8 2216.4 2253.3 2264.1 2269.4 2277.7 2228.7 2233.0 2236.5 2256.4 2266.1 2246.4 2255.2 2279.4 2283.1 2291.3 8244.3 8274.9 8350.0 8367.9 8403.4 4169.7 4545.5 4930.4 5144.9 5145.5 2851.8 3141.5 3483.1 3487.4 3506.0 833.3 851.2 1001.8 1021.3 1037.9 10342.4 10592.4 10655.4 10738.3 10745.7 4349.5 4352.5 4373.7 4398.7 4481.6 8750.3 8764.3 8802.7 8853.0 8892.0 4869.7 4889.3 4948.3 4982.4 5001.9 3287.4 4551.2 4576.4 4611.3 4676.2 3580.3 3671.7 3677.2 3688.2 3738.9 1052.4 1197.4 1208.3 1209.5 1219.3 754.2 858.6 910.9 912.8 932.1 5001.2 5011.3 5473.7 5495.8 5630.3 755.0 796.8 798.9 885.5 929.1 3712.4 3801.7 3824.5 3833.3 3899.5 8556.8 9593.1 9804.0 9829.8 9959.5 8572.4 9820.3 9936.3 9945.0 9951.6 6424.5 6727.6 7641.5 7682.2 7808.6 4711.5 4726.8 5460.3 5480.2 5548.4 8906.2 9027.2 9042.7 9150.7 9354.0 9176.9 9189.4 9295.5 9408.8 9454.6 5358.7 6598.5 6867.7 6899.1 7074.1 7728.8 7823.9 7915.0 7920.2 7946.4 7001.7 7104.2 7133.3 7156.4 7258.8 7010.0 7044.3 7048.0 7063.7 7139.4 6297.6 6326.2 6396.7 6412.2 6419.6 4741.7 4745.4 4791.8 4843.5 4846.7 4676.4 4688.5 4786.1 4865.7 4973.9 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4207.62 Median:4650.77 stddev:1311.34 (28.2%) stddev-sans-first:1281.61 08:36:34 INFO - PROCESS | 1651 | Values: 289.2 294.1 304.9 306.2 308.9 5189.1 5349.4 5365.8 5473.8 5515.3 4849.6 4942.9 5106.0 5246.8 5300.1 3686.2 3904.6 3988.6 4055.5 4087.3 3430.2 3516.2 3666.7 4048.2 4127.8 3300.5 3410.3 3416.7 3553.3 3871.8 3500.7 3539.8 3803.0 4054.8 4060.0 3355.5 3546.4 3547.0 3555.3 3579.7 4604.1 4940.7 5002.6 5287.2 5306.5 3702.9 4275.2 4291.1 4644.6 4657.9 5633.3 5635.7 5664.8 5687.0 5710.7 3531.9 3784.2 3893.5 3920.3 3931.5 5816.2 5847.3 5854.6 5864.8 5878.8 3058.9 3547.6 3635.1 3801.1 3825.1 3259.3 3385.2 3459.3 3718.5 3880.3 3595.5 3855.6 3987.5 4308.4 4423.7 4608.4 4879.7 4913.4 5573.0 5613.6 1080.1 1244.9 1280.3 1319.9 1324.5 714.9 865.0 883.7 892.5 893.2 5281.4 5285.7 5290.4 5304.7 5305.3 4633.5 4756.5 5079.9 5773.0 5810.5 4555.7 5220.4 5237.3 5262.4 5304.5 2198.4 2338.5 2398.8 2434.4 2573.7 4487.7 4729.7 5005.4 5059.1 5089.4 4540.7 4570.9 4593.4 4664.5 5027.4 4423.5 4548.3 4854.6 5000.6 5085.3 4205.6 4426.2 4540.3 4656.9 4909.9 4747.5 4812.0 4820.7 4831.5 4843.8 4642.5 4666.4 4769.6 4843.8 5068.8 3111.4 3144.6 3248.8 3342.9 3395.5 4360.2 4711.8 4824.9 4834.6 4850.7 4015.0 4135.4 4674.0 4716.9 4761.8 3903.0 4228.6 4343.0 4704.5 4762.9 3910.4 5254.1 5482.5 5557.8 5625.5 4630.8 5728.6 5741.9 5747.4 5752.9 4779.6 4889.8 4920.9 5437.1 5686.5 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:19028.74 Median:4527.85 stddev:27046.41 (597.3%) stddev-sans-first:27087.28 08:36:34 INFO - PROCESS | 1651 | Values: 702.6 706.1 722.8 776.1 823.7 13169.6 14225.8 14426.9 15076.3 15099.8 13761.7 13846.9 13881.9 13993.9 14145.2 709.7 877.9 881.3 892.9 893.7 692.9 713.1 865.3 870.1 870.6 906.1 940.3 956.2 958.2 960.5 936.3 947.9 949.8 950.1 950.2 691.9 698.3 698.4 699.8 699.9 13886.9 14415.0 14457.1 14572.1 14583.0 13366.2 13463.3 13471.2 13630.7 13731.5 12072.0 12172.3 12335.3 12404.9 12501.2 4260.3 4362.8 4396.1 4459.8 4536.6 83586.0 83871.0 84284.0 84607.6 86184.6 8984.0 10527.2 10733.9 10802.0 11185.9 20323.3 20446.2 20751.1 20776.9 20816.7 6754.7 6854.0 6912.1 6965.2 7049.5 3819.8 4210.7 4252.1 4258.9 4274.2 3053.3 3254.5 3364.3 3364.6 3773.7 677.6 683.8 691.9 704.0 705.7 557.7 630.9 660.5 675.0 685.8 7732.6 8383.2 9168.8 9176.6 9199.8 595.3 609.4 616.9 652.5 660.7 4323.7 4473.5 4490.2 4526.2 4529.5 90045.0 99240.5 102306.4 106079.5 107661.4 69208.0 94277.5 96881.5 97064.0 99021.0 50936.2 57913.4 73034.0 73165.7 76392.2 8024.3 9671.5 9729.0 9764.8 9806.4 41371.2 44319.8 52897.7 53146.6 53214.7 32925.7 46985.8 57538.4 73620.0 76725.0 12193.3 12265.8 12419.2 12551.8 12819.5 13899.6 14284.7 14361.0 14474.7 14660.6 13148.0 13248.6 13252.7 13653.8 13707.5 12753.7 12932.9 13056.3 13096.0 13599.7 12557.0 12654.2 12831.0 12851.4 13125.5 10634.5 10641.8 10786.9 10883.2 10941.4 9065.7 9808.9 11009.8 11233.3 11298.7 08:36:34 INFO - PROCESS | 1651 | -------- Summary: end -------- 08:36:34 INFO - PROCESS | 1651 | 08:36:34 INFO - TEST-INFO | 1651: exit 0 08:36:34 INFO - TEST-OK | dromaeo_css | took 1115858ms 08:36:34 INFO - TEST-START | kraken 08:36:34 INFO - operating with platform_type : mac_ 08:36:34 INFO - Initialising browser for kraken test... 08:36:34 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpZlfPBb/profile http://localhost:49280/getInfo.html) 08:36:37 INFO - PROCESS | 1664 | 2016-10-28 08:36:37.393 plugin-container[1665:9962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:36:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:37 INFO - PROCESS | 1664 | 2016-10-28 08:36:37.396 plugin-container[1665:9962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:36:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:37 INFO - PROCESS | 1664 | 2016-10-28 08:36:37.396 plugin-container[1665:9962] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:37 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 08:36:37 INFO - PROCESS | 1664 | colorDepth:24 08:36:37 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 08:36:37 INFO - PROCESS | 1664 | __metrics 08:36:37 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 08:36:38 INFO - TEST-INFO | 1664: exit 0 08:36:38 INFO - Browser initialized. 08:36:38 INFO - Running cycle 1/1 for kraken test... 08:36:38 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpZlfPBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:40 INFO - PROCESS | 1666 | 2016-10-28 08:36:40.572 plugin-container[1667:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:36:40 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:40 INFO - PROCESS | 1666 | 2016-10-28 08:36:40.573 plugin-container[1667:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a23, name = 'com.apple.CFPasteboardClient' 08:36:40 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:40 INFO - PROCESS | 1666 | 2016-10-28 08:36:40.573 plugin-container[1667:10076] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:50 INFO - PROCESS | 1666 | RSS: Main: 236199936 08:36:50 INFO - PROCESS | 1666 | 08:36:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 08:36:53 INFO - PROCESS | 1666 | RSS: Main: 248922112 08:36:53 INFO - PROCESS | 1666 | 08:36:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 08:36:57 INFO - PROCESS | 1666 | RSS: Main: 256454656 08:36:57 INFO - PROCESS | 1666 | 08:37:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 08:37:01 INFO - PROCESS | 1666 | RSS: Main: 259158016 08:37:01 INFO - PROCESS | 1666 | 08:37:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 08:37:03 INFO - PROCESS | 1666 | RSS: Main: 260603904 08:37:03 INFO - PROCESS | 1666 | 08:37:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:37:06 INFO - PROCESS | 1666 | RSS: Main: 260730880 08:37:06 INFO - PROCESS | 1666 | 08:37:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 08:37:09 INFO - PROCESS | 1666 | RSS: Main: 262557696 08:37:09 INFO - PROCESS | 1666 | 08:37:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 08:37:13 INFO - PROCESS | 1666 | RSS: Main: 262971392 08:37:13 INFO - PROCESS | 1666 | 08:37:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 08:37:17 INFO - PROCESS | 1666 | RSS: Main: 262766592 08:37:17 INFO - PROCESS | 1666 | 08:37:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:37:19 INFO - PROCESS | 1666 | RSS: Main: 262492160 08:37:19 INFO - PROCESS | 1666 | 08:37:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:37:21 INFO - PROCESS | 1666 | RSS: Main: 262561792 08:37:21 INFO - PROCESS | 1666 | 08:37:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:37:24 INFO - PROCESS | 1666 | RSS: Main: 262537216 08:37:24 INFO - PROCESS | 1666 | 08:37:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:37:28 INFO - PROCESS | 1666 | RSS: Main: 262868992 08:37:28 INFO - PROCESS | 1666 | 08:37:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:37:32 INFO - PROCESS | 1666 | RSS: Main: 262901760 08:37:32 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 08:37:34 INFO - PROCESS | 1666 | RSS: Main: 264646656 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | __start_tp_report 08:37:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 08:37:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 08:37:34 INFO - PROCESS | 1666 | |i|pagename|runs| 08:37:34 INFO - PROCESS | 1666 | |0;ai-astar;167;177;122;141;118;118;119;119;118;119 08:37:34 INFO - PROCESS | 1666 | |1;audio-beat-detection;149;119;116;154;114;119;115;118;144;116 08:37:34 INFO - PROCESS | 1666 | |2;audio-dft;198;201;205;228;198;197;196;196;205;198 08:37:34 INFO - PROCESS | 1666 | |3;audio-fft;93;84;67;85;61;62;61;73;71;62 08:37:34 INFO - PROCESS | 1666 | |4;audio-oscillator;109;106;121;104;72;71;93;70;70;89 08:37:34 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;92;89;94;93;94;88;93;90;92;88 08:37:34 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;113;151;123;97;99;99;97;107;152 08:37:34 INFO - PROCESS | 1666 | |7;imaging-desaturate;88;95;139;86;135;139;143;91;88;88 08:37:34 INFO - PROCESS | 1666 | |8;json-parse-financial;58;58;59;61;59;62;62;61;61;61 08:37:34 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;72;80;75;77;76;70;69;74;108;108 08:37:34 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;88;91;90;89;89;90;91;92;106;96 08:37:34 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;161;165;182;161;162;161;168;160;192;151 08:37:34 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;179;177;180;177;177;173;196;236;217;178 08:37:34 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;72;74;72;71;71;71;71;74;74;72 08:37:34 INFO - PROCESS | 1666 | __end_tp_report 08:37:34 INFO - PROCESS | 1666 | __start_cc_report 08:37:34 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,946 08:37:34 INFO - PROCESS | 1666 | __end_cc_report 08:37:34 INFO - PROCESS | 1666 | __startTimestamp1477669054326__endTimestamp 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | ------- Summary: start ------- 08:37:34 INFO - PROCESS | 1666 | Number of tests: 14 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:131.80 Median:120.50 stddev:22.42 (18.6%) stddev-sans-first:19.84 08:37:34 INFO - PROCESS | 1666 | Values: 167.0 177.0 122.0 141.0 118.0 118.0 119.0 119.0 118.0 119.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:119.00 stddev:15.85 (13.3%) stddev-sans-first:14.56 08:37:34 INFO - PROCESS | 1666 | Values: 149.0 119.0 116.0 154.0 114.0 119.0 115.0 118.0 144.0 116.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:202.20 Median:199.50 stddev:9.66 (4.8%) stddev-sans-first:10.12 08:37:34 INFO - PROCESS | 1666 | Values: 198.0 201.0 205.0 228.0 198.0 197.0 196.0 196.0 205.0 198.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:71.90 Median:72.00 stddev:11.66 (16.2%) stddev-sans-first:9.54 08:37:34 INFO - PROCESS | 1666 | Values: 93.0 84.0 67.0 85.0 61.0 62.0 61.0 73.0 71.0 62.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:70.50 stddev:19.05 (27.0%) stddev-sans-first:18.99 08:37:34 INFO - PROCESS | 1666 | Values: 109.0 106.0 121.0 104.0 72.0 71.0 93.0 70.0 70.0 89.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:92.50 stddev:2.36 (2.6%) stddev-sans-first:2.49 08:37:34 INFO - PROCESS | 1666 | Values: 92.0 89.0 94.0 93.0 94.0 88.0 93.0 90.0 92.0 88.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:113.50 Median:97.00 stddev:21.75 (22.4%) stddev-sans-first:22.24 08:37:34 INFO - PROCESS | 1666 | Values: 97.0 113.0 151.0 123.0 97.0 99.0 99.0 97.0 107.0 152.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:88.00 stddev:25.83 (29.3%) stddev-sans-first:26.23 08:37:34 INFO - PROCESS | 1666 | Values: 88.0 95.0 139.0 86.0 135.0 139.0 143.0 91.0 88.0 88.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:1.55 (2.5%) stddev-sans-first:1.42 08:37:34 INFO - PROCESS | 1666 | Values: 58.0 58.0 59.0 61.0 59.0 62.0 62.0 61.0 61.0 61.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:80.90 Median:74.50 stddev:14.65 (19.7%) stddev-sans-first:15.18 08:37:34 INFO - PROCESS | 1666 | Values: 72.0 80.0 75.0 77.0 76.0 70.0 69.0 74.0 108.0 108.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:90.50 stddev:5.33 (5.9%) stddev-sans-first:5.43 08:37:34 INFO - PROCESS | 1666 | Values: 88.0 91.0 90.0 89.0 89.0 90.0 91.0 92.0 106.0 96.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:163.50 stddev:11.96 (7.3%) stddev-sans-first:12.53 08:37:34 INFO - PROCESS | 1666 | Values: 161.0 165.0 182.0 161.0 162.0 161.0 168.0 160.0 192.0 151.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:179.50 stddev:21.17 (11.8%) stddev-sans-first:22.14 08:37:34 INFO - PROCESS | 1666 | Values: 179.0 177.0 180.0 177.0 177.0 173.0 196.0 236.0 217.0 178.0 08:37:34 INFO - PROCESS | 1666 | 08:37:34 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.00 stddev:1.32 (1.8%) stddev-sans-first:1.39 08:37:34 INFO - PROCESS | 1666 | Values: 72.0 74.0 72.0 71.0 71.0 71.0 71.0 74.0 74.0 72.0 08:37:34 INFO - PROCESS | 1666 | -------- Summary: end -------- 08:37:34 INFO - PROCESS | 1666 | 08:37:35 INFO - TEST-INFO | 1666: exit 0 08:37:35 INFO - TEST-OK | kraken | took 60395ms 08:37:35 INFO - SUITE-END | took 1176s 08:37:35 INFO - Completed test suite (00:19:36) 08:37:35 INFO - javascript benchmark 08:37:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7023.310062840825, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3358.781679190854, 3395.864165433383, 3412.266362732012, 3416.1801167719746, 3435.4159792835217, 5585.218069470267, 5667.773289068437, 5668.206451096845, 5719.256496655429, 5784.68184249866, 5602.635828671134, 5730.541556675466, 5731.254936858202, 5765.365809760923, 5867.677277749733, 5164.967006598696, 5224.085784987631, 5242.030224408478, 5252.579793616502, 5336.38631557372, 5229.111051121299, 5291.814786482474, 5332.200169974515, 5350.35795704516, 5389.380221274545, 5087.0, 5193.714345710984, 5256.448072952323, 5279.1817268323475, 5285.709285989269, 5237.13587258102, 5248.895022099537, 5261.500157485033, 5264.631475796696, 5288.629795914288, 5198.636095473318, 5200.609954253408, 5207.1668533034535, 5226.97386513065, 5255.921161182585, 5366.597505187087, 5530.391656917735, 5618.438156184389, 5660.179274005246, 5758.337791154029, 6110.072772100023, 6159.568830181889, 6203.193584833988, 6209.3790620938, 6244.56288059836, 6174.0, 6212.844678883019, 6214.067889816519, 6215.192025036717, 6218.160548326008, 5641.661500309994, 5690.601657883991, 5853.824385268449, 5863.9969439745555, 5915.526757859361, 6162.26052873649, 6169.0, 6180.042093475518, 6223.875522489616, 6294.968525157345, 6097.908531372033, 6154.569180157436, 6166.845828854314, 6188.319284878659, 6196.566240363221, 6069.575129740876, 6151.784687535915, 6191.318954914956, 6220.502359811204, 6240.843978900473, 5835.299764028344, 6106.82471942484, 6131.908021379683, 6154.83058218937, 6196.659183744938, 6193.225846769154, 6219.968900155561, 6229.0967809667345, 6234.688265586702, 6247.3440288769025, 4919.877003074952, 4945.876353091129, 4947.134251505972, 4952.356193694796, 4971.6271279654175, 4304.117655880537, 4351.891202719894, 4364.083542456056, 4404.603585677304, 4417.690761646654, 4784.832530861403, 5835.591508594358, 5865.0, 5905.232319798484, 5912.3496415394275, 6185.41238582343, 6219.751209951552, 6238.937610623926, 6252.218472690914, 6254.8123556293385, 4996.825111121093, 5390.353157621111, 5429.457054294539, 5834.883302333929, 5851.1515830205235, 5437.918431223535, 5439.48324909133, 5447.101228297366, 5480.177973304037, 5503.889922201613, 5251.684898906078, 6347.238331400262, 6394.296627370893, 6402.35976402356, 6404.103425520431, 6108.1903020305945, 6128.228138398679, 6142.955697531341, 6181.3200547938795, 6224.533160013108, 6288.182536270256, 6411.807645770635, 6431.0, 6445.387688169616, 6446.323136070645, 5180.870478238075, 6012.308584512837, 6198.535109866688, 6224.190855188796, 6236.688165591793, 6075.574709770451, 6139.456205378207, 6146.754129834757, 6208.379162083756, 6231.532635052298, 6087.0, 6107.389261073857, 6126.792505495216, 6139.461473530676, 6146.661933593696, 5933.643981361132, 6099.207103076572, 6161.907571386522, 6163.260408750979, 6173.722182501709, 5727.541796656341, 5904.143899134688, 5928.762849485972, 5956.583039187215, 6052.001419765689, 5850.7713380190635, 6055.6063855849225, 6078.969605151946, 6113.749968913837, 6126.23422072241, 5210.0, 5994.790182343597, 6035.1249068885645, 6072.180255665428, 6076.179715738319], "value": 5682.176971695345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.7499725145672, 495.4698472634283, 529.1956226535665, 538.9272448219492, 545.1033050631709, 31390.529142062886, 31671.524927126113, 40613.59386406135, 43742.343864842056, 43843.71172162093, 42396.364054539205, 42634.0, 42721.572784272146, 42753.78623106888, 42784.0, 8096.190380961886, 9486.43081415114, 9612.702285191515, 9613.038696130367, 9658.806823863519, 9446.480443575601, 9508.524573771318, 9579.80840383192, 9609.759756006086, 9615.855762163574, 9557.0, 9570.0, 9602.08780165883, 9606.087421694929, 9610.0, 9523.380980236263, 9567.952160239223, 9589.280803939733, 9590.856137157949, 9617.807643847083, 9447.52762361886, 9535.570899309549, 9545.0, 9577.664781732607, 9588.95205523975, 39128.0, 39209.41185882214, 39449.633022018774, 39676.40485392721, 39915.60084399148, 3370.8146051967133, 3421.1105112670634, 3449.5688038995127, 3471.3230919970615, 3498.038039539147, 30679.846600767076, 31046.534301985488, 31365.37269254602, 31858.522122168186, 31896.20259493508, 3021.8489075546354, 3153.3693261347594, 3175.888843890476, 3223.580934478526, 3255.430299697567, 410092.0, 414727.0, 414863.0, 415332.0, 417380.9130954356, 48152.759236203594, 50524.231651892005, 51104.74447627738, 56997.430025699214, 59268.70365648231, 61858.0, 62356.68821655863, 62617.68691156515, 62663.373366265754, 63131.684341577995, 15905.0, 15949.760753588705, 16122.516324510283, 16149.0, 16196.0, 12013.399330033464, 12026.458809353426, 12452.0, 12514.87485125137, 12518.06114541413, 12113.878861211451, 15903.375942969511, 15927.522174334788, 16005.599860003593, 16142.919285403732, 3190.3140824722796, 6010.0, 6278.780242691484, 6283.151774510474, 6313.390085528161, 869.8086420987372, 872.6422166911536, 969.5249327829454, 970.9223262139012, 1013.0780989299702, 24501.264962051166, 29911.700882990892, 30003.69996300009, 30203.848980754956, 30844.383112337626, 1013.5287091502339, 1031.6440827914357, 1036.9325993810378, 1062.1449732964884, 1138.4250953268547, 13384.19694818314, 15717.842821571638, 15719.371225150866, 15885.761713574306, 16166.030038197745, 626182.0, 626905.0, 633460.0, 634288.0, 635689.0, 58175.709121454965, 58291.70854145787, 58580.82838343209, 58655.70672146697, 58697.70651146802, 1333.9132956357805, 1376.8829649479906, 1385.3350391811987, 1393.136255521583, 1402.3689339797131, 25196.118135865156, 29301.85349073241, 30071.849640751658, 30585.54121688132, 30768.230794230323, 48597.02805943932, 50443.49556504388, 50773.23840142327, 50848.0, 50982.23526647178, 588673.0, 592310.0, 592515.0, 593269.0, 593392.0, 25524.23427297184, 26307.868460657573, 26446.471070578096, 26754.59868102019, 26812.0, 42584.78707606442, 46586.36947706814, 46709.59871203869, 47166.05667886692, 48511.0, 29769.404611907208, 39705.602943971346, 40036.399454008795, 40245.0, 41069.794651026554, 38823.611763882, 38898.61101389063, 39263.60736392599, 40160.397594036694, 40248.597514025656, 37329.62670373262, 38269.043273918374, 38468.42297365598, 38484.80757596194, 41506.79246603748, 30390.69609303967, 34241.0, 34268.82865585656, 34415.82792086023, 34493.13767155841, 24743.50512989766, 24890.875545622155, 25573.74426255788, 25824.612630810178, 25958.0], "value": 19440.300235474882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.0020738406932, 364.79389145132996, 366.92111196092833, 375.3187963845622, 390.69313147521547, 15425.305861236244, 19480.805191948093, 20127.49681257969, 20762.065707043163, 21021.579568408662, 19754.30859919903, 19936.90031549844, 20001.099950502226, 20390.592188156228, 20492.38522844317, 5012.172991456406, 5726.3700992890745, 5812.099124635667, 5898.41015898411, 6015.6992150392525, 5779.595428320019, 5978.910316345258, 6002.459778619946, 6010.789622363218, 6024.3674414186535, 5655.688937108457, 5898.439648233411, 5910.231669882909, 5918.585699001072, 5943.791967281146, 5952.523798096142, 6016.879662406727, 6041.78853740117, 6042.486388656991, 6046.486048685867, 5143.254228136899, 5223.6343455958095, 5234.18870075139, 5458.426865179139, 5487.204355368489, 12947.482100716054, 12968.481260749653, 13224.735505289937, 15706.607334816721, 24058.51882962331, 2006.8795872247713, 2313.3921924262645, 2420.7095148582284, 2581.1998280533094, 2746.5056289867816, 9483.542417091045, 9674.90325096747, 9951.800963980751, 10220.0, 10486.737831554196, 1762.7444020617068, 1830.2404502131599, 1861.6276744650988, 1864.766904136983, 1876.9249230030782, 95319.52340238323, 111054.0, 116110.83889161085, 119973.0, 120780.39609801894, 10701.197410194267, 11336.206465547333, 11353.829692554618, 11373.772524549462, 11393.829092563617, 14307.070040447337, 14336.068155569854, 14341.0, 14343.641408964859, 14391.424343026161, 6861.55399899005, 8133.79665508367, 8434.650455927022, 8537.402381833212, 8573.0, 8249.505029698237, 8265.090840007715, 8280.00639923201, 8381.622826972802, 8411.747647570583, 3578.8905439313376, 3652.543432070991, 3692.538432695913, 4281.978590107092, 4561.087782443525, 1508.8415716349625, 1744.7960906974147, 1827.8080801515916, 1922.5866438715743, 1953.6288105259955, 892.3262936482914, 899.7030979776658, 908.8136931928939, 930.223263574909, 945.4563625915126, 16537.338506459848, 19502.90248548748, 20127.798722012885, 20898.059587318305, 21024.053917573747, 935.5322338830584, 966.2028826218398, 1034.5137785241075, 1049.4123290957089, 1064.1912146768511, 10603.89396106029, 11027.276847300493, 11101.111911047097, 11130.888691113147, 11135.33188008722, 532019.0, 579260.0, 580485.0, 592861.0, 611770.0, 118045.0, 120585.39707301407, 121919.0, 127507.0, 134793.0, 955.6989548292265, 977.8777652793401, 1001.9949900250452, 1002.1431675917132, 1023.1456733627505, 18380.540486487946, 19133.425997220107, 19167.616647667248, 19168.808311916702, 19266.71099933474, 43145.784271078446, 54433.45566544284, 54588.72705636446, 54747.726261368436, 55091.0, 64673.35326646861, 80547.19452805397, 80570.79143812964, 80874.0, 81685.59157204175, 7660.5403675779635, 7780.649870755717, 7969.083555390972, 8017.0, 8021.5935219435105, 11885.0, 11904.940475297568, 11952.071742423163, 12347.259164450163, 12476.937615311865, 8694.739157825275, 10087.445190514592, 10598.152147828001, 10776.568937242422, 10821.404822734825, 10500.632477863544, 10513.002529519366, 10639.946800265949, 10735.892641073802, 10797.758257800138, 8904.86642700347, 9628.71113866585, 9666.371685840399, 10991.571095757501, 11041.668749937515, 7767.883481747891, 7885.605719714106, 7913.169117242837, 7943.801404964922, 7957.721479748181, 10208.387496750218, 10629.840552391875, 10821.9458902705, 10932.56269749201, 10984.340939543654], "value": 9220.645858648297, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.984650153498, 1565.9451919182832, 1789.2306308287439, 1863.5248011757, 1898.4684288399244, 7329.083864516935, 7552.282533159355, 7920.366370690345, 8383.748487545376, 8384.62269197886, 7866.763997080083, 8163.632636531355, 8178.182181781819, 8286.709965151222, 8302.95848520758, 2219.2676416782506, 2267.807236384909, 2271.2731925783755, 2271.931842044733, 2276.0668126068317, 2251.234580242717, 2260.5026894083276, 2261.118163916074, 2295.4376177836452, 2309.8267629927823, 2216.423729830248, 2253.256425379621, 2264.116994372196, 2269.353234328217, 2277.7380601230907, 2228.699125618045, 2232.966505502419, 2236.519148383089, 2256.4020534558354, 2266.0709109265204, 2246.4383904023994, 2255.22194842779, 2279.361778701966, 2283.063943783041, 2291.2667946257206, 8244.258016778458, 8274.875876861792, 8350.0, 8367.916320836775, 8403.41176117672, 4169.687273454473, 4545.5227051208485, 4930.383702037246, 5144.871378215537, 5145.536901678867, 2851.82889026657, 3141.513065474855, 3483.059573915054, 3487.4420092785285, 3505.964940350589, 833.2792134803412, 851.2253848997444, 1001.8346972749488, 1021.2799976016964, 1037.9481025948671, 10342.379457232515, 10592.411138329313, 10655.413952232702, 10738.30201036938, 10745.731356716145, 4349.456317960255, 4352.477702675636, 4373.737575745466, 4398.692091553625, 4481.596656300949, 8750.256228220545, 8764.298856091498, 8802.735917922473, 8853.026167121612, 8892.0, 4869.732164730974, 4889.339939108246, 4948.307236986825, 4982.377202849644, 5001.924971125436, 3287.3589650018016, 4551.158035763395, 4576.405067341224, 4611.2852507861335, 4676.158291507561, 3580.3197392495345, 3671.7429779915683, 3677.1726361568435, 3688.151725103188, 3738.9439158412647, 1052.4474650808386, 1197.3713800254936, 1208.3173007250875, 1209.4617895036624, 1219.3415555600054, 754.2193829386558, 858.5750053723322, 910.8906931168302, 912.7900582865979, 932.072587775168, 5001.249812528077, 5011.323471331323, 5473.713677285835, 5495.807646732425, 5630.324361076697, 754.9509281896659, 796.7968168117093, 798.9161138080534, 885.521818218155, 929.1126973740062, 3712.424574191005, 3801.7338786284695, 3824.4836947012354, 3833.310004199215, 3899.512560929884, 8556.802047713325, 9593.088656577615, 9803.950980245054, 9829.803403932025, 9959.452230127412, 8572.442791218551, 9820.312578119463, 9936.254780891462, 9945.0, 9951.552180151766, 6424.486041116793, 6727.596344219362, 7641.465097443125, 7682.1549629540705, 7808.648610812527, 4711.505291944297, 4726.7636618169645, 5460.31746031746, 5480.177973304037, 5548.4174161712435, 8906.198442140238, 9027.187553120251, 9042.728718138467, 9150.725478235663, 9354.01782812822, 9176.862347064933, 9189.356745027784, 9295.488748118918, 9408.81182376335, 9454.621815127319, 5358.678479291255, 6598.538102332791, 6867.725290988505, 6899.103116594811, 7074.115735533059, 7728.806779830549, 7823.882641760264, 7915.010623672041, 7920.247576480225, 7946.364290856834, 7001.719931202899, 7104.18301895268, 7133.322334378226, 7156.427485801228, 7258.818529536804, 7010.037189586924, 7044.330788575077, 7047.964760176166, 7063.6821343038655, 7139.357457828821, 6297.590656607306, 6326.177596912372, 6396.712147953446, 6412.198475190601, 6419.64691941929, 4741.715497070187, 4745.406824146981, 4791.784369703302, 4843.46721860588, 4846.660733748745, 4676.3686902267755, 4688.460827004802, 4786.138495070921, 4865.70805751656, 4973.875653108701], "value": 4275.431984176846, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.178732399984, 294.0897920934707, 304.90700336397396, 306.2283046722259, 308.9320349523104, 5189.117849965505, 5349.438308977559, 5365.839024829256, 5473.753681084351, 5515.283013208283, 4849.636277279209, 4942.937268487274, 5105.978804239148, 5246.799452334073, 5300.104423248909, 3686.2443199144177, 3904.609539046087, 3988.601139886003, 4055.472788537486, 4087.3255912774357, 3430.2281986552953, 3516.1736991806906, 3666.7244753858704, 4048.1701251243426, 4127.793610319487, 3300.5214243934615, 3410.283840393508, 3416.692497675209, 3553.271579326245, 3871.7676939383587, 3500.737444691633, 3539.8407071681836, 3802.954187598405, 4054.7972601369815, 4059.97970010151, 3355.5302257683943, 3546.4218422801864, 3546.9536486736474, 3555.306715190539, 3579.731520136, 4604.102200070987, 4940.678855874393, 5002.574781143607, 5287.206918962186, 5306.52241298285, 3702.8889133326043, 4275.166342563201, 4291.077418355068, 4644.6052085572765, 4657.88355291117, 5633.338330085437, 5635.690037048002, 5664.830055098354, 5687.0, 5710.685912274823, 3531.9293614127832, 3784.202503810668, 3893.4886997886206, 3920.2551515212303, 3931.528216613996, 5816.156657284712, 5847.26909136358, 5854.648721076706, 5864.824055278348, 5878.823635290948, 3058.908232753021, 3547.609762926076, 3635.1457407509215, 3801.144742432986, 3825.0628595993603, 3259.3481303739345, 3385.1875549868346, 3459.3081383723356, 3718.5165928429674, 3880.3015457217407, 3595.5146055282203, 3855.5566109897723, 3987.52149742027, 4308.418363520947, 4423.712458690175, 4608.377868987711, 4879.731614761222, 4913.385826771653, 5573.0, 5613.550915926716, 1080.0819303591884, 1244.8547336450488, 1280.3342262023696, 1319.920804751718, 1324.4635922451423, 714.9142102947679, 864.9697260595849, 883.6862913665685, 892.5358813416987, 893.1738142218422, 5281.392639846466, 5285.709285989307, 5290.444503327172, 5304.655197412156, 5305.283786688825, 4633.497911128021, 4756.476787553366, 5079.907819818683, 5772.9711351442975, 5810.463228867524, 4555.703879247838, 5220.425753167148, 5237.292965449616, 5262.368515778131, 5304.522592966651, 2198.40643026382, 2338.5439839231526, 2398.8320817542954, 2434.379233295498, 2573.7426257374113, 4487.730736155841, 4729.692569983009, 5005.4232916631145, 5059.089363914531, 5089.440161582223, 4540.659450541157, 4570.908581828345, 4593.448786145684, 4664.510226426177, 5027.371578552681, 4423.491298500714, 4548.2716567704065, 4854.611631069506, 5000.599592277286, 5085.3134826797905, 4205.600467955539, 4426.247537918497, 4540.341650460731, 4656.93014604788, 4909.877253068559, 4747.489884609566, 4811.975940120277, 4820.71075735457, 4831.4685384608365, 4843.757812109451, 4642.53574642533, 4666.440027196622, 4769.57073863354, 4843.764839965786, 5068.767579071369, 3111.424386488507, 3144.5754823099505, 3248.7563432742168, 3342.866285348559, 3395.49067639856, 4360.234105187405, 4711.8350857719815, 4824.877054096187, 4834.5890599299455, 4850.660453768202, 4015.017623740547, 4135.441715368507, 4673.976630116827, 4716.858494245178, 4761.785719642555, 3902.965714085713, 4228.640565551963, 4342.957690154277, 4704.529547045268, 4762.880927976828, 3910.4329872168946, 5254.13306804373, 5482.464909825256, 5557.833265002057, 5625.46558076982, 4630.76846157684, 5728.598998070095, 5741.856453588526, 5747.396523365153, 5752.864688744728, 4779.617630589614, 4889.804407823647, 4920.852374428773, 5437.075697131418, 5686.545076393796], "value": 3799.333389508176, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.5644100657593, 706.1498872952861, 722.819295176206, 776.1074764021374, 823.7199352220244, 13169.604911852648, 14225.78661320081, 14426.855731442682, 15076.32156552955, 15099.773503397459, 13761.724765504683, 13846.930765346184, 13881.861181388183, 13993.93003034986, 14145.222012789292, 709.7196607340085, 877.9253763430116, 881.3478026260585, 892.9151730585584, 893.7229458867772, 692.8856738638119, 713.1406654980735, 865.3466632692308, 870.1081391573656, 870.6430363550945, 906.071276941134, 940.3229674634252, 956.2254573795248, 958.1903291718487, 960.4525420510312, 936.2556767369957, 947.8625599288099, 949.8100379924043, 950.0594411532533, 950.2493030505892, 691.9169699636076, 698.2982102986466, 698.3784431855602, 699.8215455058978, 699.9300069993011, 13886.93056534721, 14415.044915792343, 14457.060291081047, 14572.052816566995, 14583.0, 13366.198028118344, 13463.326833658375, 13471.191728496322, 13630.727385452334, 13731.519396821062, 12072.0, 12172.26966382011, 12335.321557314344, 12404.875951240552, 12501.179403395714, 4260.318349064135, 4362.825486980517, 4396.1427521633295, 4459.799309031101, 4536.56902594253, 83586.0, 83871.0, 84284.0, 84607.57696211542, 86184.56907715484, 8984.0, 10527.21045921559, 10733.946330268298, 10802.0, 11185.888141118647, 20323.288684895957, 20446.182152713725, 20751.066202020946, 20776.896115519325, 20816.687749683766, 6754.729810807611, 6853.965730171317, 6912.101426814482, 6965.164180298295, 7049.506534542636, 3819.809009549567, 4210.705250632426, 4252.149570085995, 4258.87223383299, 4274.230638485041, 3053.3130045739977, 3254.5118232264876, 3364.293498365371, 3364.6130694970393, 3773.716971227114, 677.6036018928884, 683.8324610470466, 691.8650863081751, 703.9507234493639, 705.6683358821346, 557.6542543622899, 630.8769789890928, 660.4683229999896, 674.9696263668146, 685.8251145957848, 7732.57470839098, 8383.245507904317, 9168.81662366749, 9176.632934682671, 9199.77000574991, 595.3329085696106, 609.3845216331537, 616.9352218017133, 652.4519403700914, 660.725798793505, 4323.718958267703, 4473.507914129443, 4490.236659767847, 4526.185286648435, 4529.5243999380245, 90045.0, 99240.50379748055, 102306.3731494775, 106079.46960265304, 107661.38507922388, 69208.0, 94277.5286123565, 96881.51559242158, 97064.0, 99021.0, 50936.21723239669, 57913.420865791646, 73034.0, 73165.73268212647, 76392.23607763962, 8024.307025626884, 9671.468069256258, 9729.0, 9764.804703906024, 9806.362586431858, 41371.172576547695, 44319.77840110779, 52897.735511323735, 53146.573486698995, 53214.73392633012, 32925.68297268082, 46985.76507117443, 57538.424615753305, 73620.0, 76725.0, 12193.32936688455, 12265.816012759995, 12419.19275247106, 12551.811722823983, 12819.487220511075, 13899.583012509642, 14284.71430571362, 14361.0, 14474.710505790035, 14660.633484162981, 13148.0, 13248.602541923758, 13252.668683282995, 13653.79519307231, 13707.451701931812, 12753.74492510126, 12932.870671293167, 13056.34718264102, 13096.0, 13599.728005440034, 12557.0, 12654.177478463871, 12831.0, 12851.357432128543, 13125.474981000652, 10634.521446534769, 10641.787164256517, 10786.913358742659, 10883.23817332779, 10941.398223097805, 9065.728028159167, 9808.852867206855, 11009.83485247738, 11233.32600044, 11298.661040168809], "value": 7253.272219177669, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 177.0, 122.0, 141.0, 118.0, 118.0, 119.0, 119.0, 118.0, 119.0], "value": 131.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 116.0, 154.0, 114.0, 119.0, 115.0, 118.0, 144.0, 116.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 205.0, 228.0, 198.0, 197.0, 196.0, 196.0, 205.0, 198.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 84.0, 67.0, 85.0, 61.0, 62.0, 61.0, 73.0, 71.0, 62.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 121.0, 104.0, 72.0, 71.0, 93.0, 70.0, 70.0, 89.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 94.0, 93.0, 94.0, 88.0, 93.0, 90.0, 92.0, 88.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 113.0, 151.0, 123.0, 97.0, 99.0, 99.0, 97.0, 107.0, 152.0], "value": 113.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 95.0, 139.0, 86.0, 135.0, 139.0, 143.0, 91.0, 88.0, 88.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 61.0, 59.0, 62.0, 62.0, 61.0, 61.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 75.0, 77.0, 76.0, 70.0, 69.0, 74.0, 108.0, 108.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 90.0, 89.0, 89.0, 90.0, 91.0, 92.0, 106.0, 96.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 182.0, 161.0, 162.0, 161.0, 168.0, 160.0, 192.0, 151.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 180.0, 177.0, 177.0, 173.0, 196.0, 236.0, 217.0, 178.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 72.0, 71.0, 71.0, 71.0, 71.0, 74.0, 74.0, 72.0], "value": 72.2, "unit": "score"}]}]} 08:37:35 INFO - Return code: 0 08:37:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:37:35 ERROR - # TBPL SUCCESS # 08:37:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:37:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:37:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:37:35 INFO - 2016-10-28 08:37:35.651 screenresolution[1671:10456] starting screenresolution argv=screenresolution get 08:37:35 INFO - 2016-10-28 08:37:35.654 screenresolution[1671:10456] Display 0: 1600x1200x32@60 08:37:35 INFO - 2016-10-28 08:37:35.660 screenresolution[1672:10459] starting screenresolution argv=screenresolution list 08:37:35 INFO - Available Modes on Display 0 08:37:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:37:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:37:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:37:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:37:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:37:35 INFO - Intel Iris: 08:37:35 INFO - Chipset Model: Intel Iris 08:37:35 INFO - Type: GPU 08:37:35 INFO - Bus: Built-In 08:37:35 INFO - VRAM (Dynamic, Max): 1536 MB 08:37:35 INFO - Vendor: Intel (0x8086) 08:37:35 INFO - Device ID: 0x0a2e 08:37:35 INFO - Revision ID: 0x0009 08:37:35 INFO - Displays: 08:37:35 INFO - Display: 08:37:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:37:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:37:35 INFO - Display Serial Number: 1600x1200 60 08:37:35 INFO - Main Display: Yes 08:37:35 INFO - Mirror: Off 08:37:35 INFO - Online: Yes 08:37:35 INFO - Rotation: Supported 08:37:35 INFO - Return code: 0 08:37:35 INFO - Running post-action listener: _resource_record_post_action 08:37:35 INFO - [mozharness: 2016-10-28 15:37:35.893962Z] Finished run-tests step (success) 08:37:35 INFO - Running post-run listener: _resource_record_post_run 08:37:35 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38435328; Write bytes: 709190656; Read time: 245; Write time: 2014 08:37:35 INFO - TinderboxPrint: CPU usage
54.7% 08:37:35 INFO - TinderboxPrint: I/O read bytes / time
38,435,328 / 245 08:37:35 INFO - TinderboxPrint: I/O write bytes / time
709,190,656 / 2,014 08:37:35 INFO - TinderboxPrint: CPU idle
2,196.0 (45.3%) 08:37:35 INFO - TinderboxPrint: CPU system
984.4 (20.3%) 08:37:35 INFO - TinderboxPrint: CPU user
1,669.4 (34.4%) 08:37:35 INFO - TinderboxPrint: Swap in / out
443,973,632 / 0 08:37:35 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406063616; Write bytes: 407050240; Read time: 30868; Write time: 868 08:37:35 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35437056; Write bytes: 300579840; Read time: 223; Write time: 1139 08:37:36 INFO - Running post-run listener: _upload_blobber_files 08:37:36 INFO - Blob upload gear active. 08:37:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:37:36 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 08:37:36 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:37:36 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:37:36 INFO - (blobuploader) - INFO - Open directory for files ... 08:37:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:37:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:37:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:37 INFO - (blobuploader) - INFO - Done attempting. 08:37:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:37:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:37:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:38 INFO - (blobuploader) - INFO - Done attempting. 08:37:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:37:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:37:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:40 INFO - (blobuploader) - INFO - Done attempting. 08:37:40 INFO - (blobuploader) - INFO - Iteration through files over. 08:37:41 INFO - Return code: 0 08:37:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:37:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:37:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab7cf811c50d7816474c170babb69793fffcd0b2ef25523567af3a65c82b4c49b0cb8c7224e46ed2e2bc8e635c6ee233606f7d5f894f2bf475ed96e46ab520f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b4ee768883712f299ba9d632aa7daa3fcd186cf7cc9caf35a018623ab2f11e54831c1cb2c7bf0f22cb50ba7cbcefd4e5ab759365f5127ad820ae16a62ecc234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498a8a81849ea4576a61cf4652051112005aa31758b05f05f51f3d9ff2299c077325d8594863b9592b54b92e79b058c67558e47984952cef1ef87f9f709c5bf7"} 08:37:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:37:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:37:41 INFO - Contents: 08:37:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab7cf811c50d7816474c170babb69793fffcd0b2ef25523567af3a65c82b4c49b0cb8c7224e46ed2e2bc8e635c6ee233606f7d5f894f2bf475ed96e46ab520f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b4ee768883712f299ba9d632aa7daa3fcd186cf7cc9caf35a018623ab2f11e54831c1cb2c7bf0f22cb50ba7cbcefd4e5ab759365f5127ad820ae16a62ecc234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498a8a81849ea4576a61cf4652051112005aa31758b05f05f51f3d9ff2299c077325d8594863b9592b54b92e79b058c67558e47984952cef1ef87f9f709c5bf7"} 08:37:41 INFO - Running post-run listener: copy_logs_to_upload_dir 08:37:41 INFO - Copying logs to upload dir... 08:37:41 INFO - mkdir: /builds/slave/test/build/upload/logs 08:37:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.120667 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-28 08:37:41.113440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 08:37:41.122225) ========= 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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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/Firefox/sha512/6ab7cf811c50d7816474c170babb69793fffcd0b2ef25523567af3a65c82b4c49b0cb8c7224e46ed2e2bc8e635c6ee233606f7d5f894f2bf475ed96e46ab520f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b4ee768883712f299ba9d632aa7daa3fcd186cf7cc9caf35a018623ab2f11e54831c1cb2c7bf0f22cb50ba7cbcefd4e5ab759365f5127ad820ae16a62ecc234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498a8a81849ea4576a61cf4652051112005aa31758b05f05f51f3d9ff2299c077325d8594863b9592b54b92e79b058c67558e47984952cef1ef87f9f709c5bf7"} build_url:https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011158 build_url: 'https://queue.taskcluster.net/v1/task/IysMNi3rRS-_ft_jOFx11Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab7cf811c50d7816474c170babb69793fffcd0b2ef25523567af3a65c82b4c49b0cb8c7224e46ed2e2bc8e635c6ee233606f7d5f894f2bf475ed96e46ab520f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b4ee768883712f299ba9d632aa7daa3fcd186cf7cc9caf35a018623ab2f11e54831c1cb2c7bf0f22cb50ba7cbcefd4e5ab759365f5127ad820ae16a62ecc234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498a8a81849ea4576a61cf4652051112005aa31758b05f05f51f3d9ff2299c077325d8594863b9592b54b92e79b058c67558e47984952cef1ef87f9f709c5bf7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 08:37:41.165378) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:37:41.165778) ========= 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.HxhtmXD3YQ/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.6AgHDrHmLX/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.005303 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:37:41.216576) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 08:37:41.218993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 08:37:41.219354) ========= ========= Total master_lag: 0.22 =========