builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0248 starttime: 1480424393.17 results: success (0) buildid: 20161129024026 builduid: abe4419b94d34e9eb0cc44607b99bd39 revision: f8107cf961444a8d7fdc2c0f446238af9893f875 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.169668) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.170242) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.170568) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.651721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.652039) ========= 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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 04:59:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.2M=0.001s 2016-11-29 04:59:53 (21.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.213905 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.891126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.891631) ========= 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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.025633 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:53.944278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 04:59:53.944831) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8107cf961444a8d7fdc2c0f446238af9893f875 --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 f8107cf961444a8d7fdc2c0f446238af9893f875 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 04:59:54,043 truncating revision to first 12 chars 2016-11-29 04:59:54,043 Setting DEBUG logging. 2016-11-29 04:59:54,043 attempt 1/10 2016-11-29 04:59:54,043 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8107cf96144?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 04:59:55,021 unpacking tar archive at: mozilla-central-f8107cf96144/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253836 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 04:59:55.224119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:55.224438) ========= script_repo_revision: f8107cf961444a8d7fdc2c0f446238af9893f875 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:55.224886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:55.225167) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 04:59:55.245057) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-11-29 04:59:55.245445) ========= /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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 04:59:55 INFO - MultiFileLogger online at 20161129 04:59:55 in /builds/slave/test 04:59:55 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 04:59:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:55 INFO - {'append_to_log': False, 04:59:55 INFO - 'base_work_dir': '/builds/slave/test', 04:59:55 INFO - 'blob_upload_branch': 'Firefox', 04:59:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:55 INFO - 'branch': 'Firefox', 04:59:55 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:55 INFO - 'config_files': ('talos/mac_config.py',), 04:59:55 INFO - 'default_actions': ('clobber', 04:59:55 INFO - 'read-buildbot-config', 04:59:55 INFO - 'download-and-extract', 04:59:55 INFO - 'populate-webroot', 04:59:55 INFO - 'create-virtualenv', 04:59:55 INFO - 'install', 04:59:55 INFO - 'run-tests'), 04:59:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:55 INFO - 'download_minidump_stackwalk': True, 04:59:55 INFO - 'download_symbols': 'ondemand', 04:59:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:55 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:55 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:55 INFO - 'installer_path': 'installer.exe', 04:59:55 INFO - 'log_level': 'info', 04:59:55 INFO - 'log_name': 'talos', 04:59:55 INFO - 'log_to_console': True, 04:59:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:55 INFO - 'opt_config_files': (), 04:59:55 INFO - 'pip_index': False, 04:59:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:55 INFO - 'cmd': ('bash', 04:59:55 INFO - '-c', 04:59:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:55 INFO - 'enabled': True, 04:59:55 INFO - 'halt_on_failure': False, 04:59:55 INFO - 'name': 'check_screen_resolution'},), 04:59:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:55 INFO - 'cmd': ('bash', 04:59:55 INFO - '-c', 04:59:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:55 INFO - 'enabled': True, 04:59:55 INFO - 'halt_on_failure': False, 04:59:55 INFO - 'name': 'check_screen_resolution'},), 04:59:55 INFO - 'run_cmd_checks_enabled': True, 04:59:55 INFO - 'sps_profile': False, 04:59:55 INFO - 'sps_profile_interval': 0, 04:59:55 INFO - 'suite': 'dromaeojs-e10s', 04:59:55 INFO - 'system_bits': '32', 04:59:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:55 INFO - 'title': 't-yosemite-r7-0248', 04:59:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:55 INFO - 'use_talos_json': True, 04:59:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:55 INFO - 'work_dir': 'build'} 04:59:55 INFO - [mozharness: 2016-11-29 12:59:55.449341Z] Running clobber step. 04:59:55 INFO - Running pre-action listener: _resource_record_pre_action 04:59:55 INFO - Running main action method: clobber 04:59:55 INFO - rmtree: /builds/slave/test/build 04:59:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:57 INFO - Running post-action listener: _resource_record_post_action 04:59:57 INFO - [mozharness: 2016-11-29 12:59:57.590247Z] Finished clobber step (success) 04:59:57 INFO - [mozharness: 2016-11-29 12:59:57.590368Z] Running read-buildbot-config step. 04:59:57 INFO - Running pre-action listener: _resource_record_pre_action 04:59:57 INFO - Running main action method: read_buildbot_config 04:59:57 INFO - Using buildbot properties: 04:59:57 INFO - { 04:59:57 INFO - "project": "", 04:59:57 INFO - "product": "firefox", 04:59:57 INFO - "script_repo_revision": "production", 04:59:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:59:57 INFO - "repository": "", 04:59:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:59:57 INFO - "buildid": "20161129024026", 04:59:57 INFO - "pgo_build": "False", 04:59:57 INFO - "basedir": "/builds/slave/test", 04:59:57 INFO - "buildnumber": 99, 04:59:57 INFO - "slavename": "t-yosemite-r7-0248", 04:59:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:59:57 INFO - "platform": "yosemite_r7", 04:59:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:59:57 INFO - "branch": "mozilla-central", 04:59:57 INFO - "repo_path": "mozilla-central", 04:59:57 INFO - "revision": "f8107cf961444a8d7fdc2c0f446238af9893f875", 04:59:57 INFO - "stage_platform": "macosx64", 04:59:57 INFO - "builduid": "abe4419b94d34e9eb0cc44607b99bd39", 04:59:57 INFO - "slavebuilddir": "test" 04:59:57 INFO - } 04:59:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:59:57 INFO - Running post-action listener: _resource_record_post_action 04:59:57 INFO - [mozharness: 2016-11-29 12:59:57.593599Z] Finished read-buildbot-config step (success) 04:59:57 INFO - [mozharness: 2016-11-29 12:59:57.593729Z] Running download-and-extract step. 04:59:57 INFO - Running pre-action listener: _resource_record_pre_action 04:59:57 INFO - Running main action method: download_and_extract 04:59:57 INFO - mkdir: /builds/slave/test/build/tests 04:59:57 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')]} 04:59:57 INFO - trying https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:59:57 INFO - Downloading https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:59:58 INFO - Downloaded 1199 bytes. 04:59:58 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:59:58 INFO - Using the following test package requirements: 04:59:58 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:59:58 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:58 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:59:58 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'jsshell-mac.zip'], 04:59:58 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:59:58 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:59:58 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:59:58 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:59:58 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:59:58 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:59:58 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:58 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:59:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:58 INFO - Fetch https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:59:59 INFO - Http code: 200 04:59:59 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 04:59:59 INFO - x-amz-cf-id: OnBYtEnlBkKtA-ol6B5iLZCEBbJ2bQ5H0wb2wp8Y8JULq5erygfb_Q== 04:59:59 INFO - x-amz-version-id: vIShEtnJC0J1fSQoeVMyJTEZgceVoYZ. 04:59:59 INFO - Expected file size: 16284835 04:59:59 INFO - Obtained file size: 16284835 05:00:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:00:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:00:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:00:05 INFO - Fetch https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:00:06 INFO - Http code: 200 05:00:06 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 05:00:06 INFO - x-amz-cf-id: tOxaADi9Rli7oErxpQeJ32rO2bl12TDBB5eJn5vpxnk_tvC4MPeq6Q== 05:00:06 INFO - x-amz-version-id: vIShEtnJC0J1fSQoeVMyJTEZgceVoYZ. 05:00:06 INFO - Expected file size: 16284835 05:00:06 INFO - Obtained file size: 16284835 05:00:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:00:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:00:12 INFO - Fetch https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:00:13 INFO - Http code: 200 05:00:13 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 05:00:13 INFO - x-amz-cf-id: 1ggdsBuZePQDZJk4Q3Omgp7YVlAUQPrWz-BT0ZJUG9jpC9hVxEL9-g== 05:00:13 INFO - x-amz-version-id: JmC.nzSuIIkyzmBFjTXBTvDMhFE7DXtG 05:00:14 INFO - Expected file size: 14409702 05:00:14 INFO - Obtained file size: 14409702 05:00:14 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')]} 05:00:14 INFO - trying https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:00:14 INFO - Downloading https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:00:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:00:24 INFO - Downloaded 107587365 bytes. 05:00:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:00:24 INFO - mkdir: /builds/slave/test/properties 05:00:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:24 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:24 INFO - Contents: 05:00:24 INFO - build_url:https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:00:25 INFO - Running post-action listener: _resource_record_post_action 05:00:25 INFO - Running post-action listener: set_extra_try_arguments 05:00:25 INFO - [mozharness: 2016-11-29 13:00:25.706415Z] Finished download-and-extract step (success) 05:00:25 INFO - [mozharness: 2016-11-29 13:00:25.706533Z] Running populate-webroot step. 05:00:25 INFO - Running pre-action listener: _resource_record_pre_action 05:00:25 INFO - Running main action method: populate_webroot 05:00:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:25 INFO - '--tppagecycles', 05:00:25 INFO - '7'], 05:00:25 INFO - 'tests': ['tsvgm']}}, 05:00:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:00:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:00:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:00:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['damp', 'tps']}, 05:00:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'tests': ['damp', 'tps']}, 05:00:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['dromaeo_dom']}, 05:00:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['basic_compositor_video']}, 05:00:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:00:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'other-e10s': {'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['a11yr', 05:00:25 INFO - 'ts_paint', 05:00:25 INFO - 'tpaint', 05:00:25 INFO - 'sessionrestore', 05:00:25 INFO - 'sessionrestore_no_auto_restore', 05:00:25 INFO - 'tabpaint']}, 05:00:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['tsvgx', 05:00:25 INFO - 'tsvgr_opacity', 05:00:25 INFO - 'tart', 05:00:25 INFO - 'tscrollx', 05:00:25 INFO - 'cart', 05:00:25 INFO - 'tsvg_static']}, 05:00:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:00:25 INFO - 'tsvgr_opacity', 05:00:25 INFO - 'tart', 05:00:25 INFO - 'tscrollx', 05:00:25 INFO - 'cart', 05:00:25 INFO - 'tsvg_static']}, 05:00:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'talos_options': ['--disable-e10s'], 05:00:25 INFO - 'tests': ['tp5o']}, 05:00:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:00:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'talos_options': ['--disable-e10s', 05:00:25 INFO - '--xperf_path', 05:00:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:25 INFO - 'tests': ['tp5n']}, 05:00:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:00:25 INFO - 'talos_options': ['--xperf_path', 05:00:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:25 INFO - 'tests': ['tp5n']}}, 05:00:25 INFO - 'talos.zip': {'path': '', 05:00:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:25 INFO - Running post-action listener: _resource_record_post_action 05:00:25 INFO - [mozharness: 2016-11-29 13:00:25.724589Z] Finished populate-webroot step (success) 05:00:25 INFO - [mozharness: 2016-11-29 13:00:25.724729Z] Running create-virtualenv step. 05:00:25 INFO - Running pre-action listener: _resource_record_pre_action 05:00:25 INFO - Running main action method: create_virtualenv 05:00:25 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:25 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 05:00:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:25 INFO - Using real prefix '/tools/python27' 05:00:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:26 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:29 INFO - Installing pip.................done. 05:00:29 INFO - Return code: 0 05:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:00:29 INFO - Reading from file tmpfile_stdout 05:00:29 INFO - Output received: 05:00:29 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) 05:00:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:30 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:30 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 05:00:30 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 05:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:30 INFO - 'HOME': '/Users/cltbld', 05:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:30 INFO - 'LOGNAME': 'cltbld', 05:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:30 INFO - 'MOZ_NO_REMOTE': '1', 05:00:30 INFO - 'NO_EM_RESTART': '1', 05:00:30 INFO - 'PAGER': '/bin/cat', 05:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:30 INFO - 'PWD': '/builds/slave/test', 05:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:30 INFO - 'SHELL': '/bin/bash', 05:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:30 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:30 INFO - 'USER': 'cltbld', 05:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:30 INFO - 'XPC_FLAGS': '0x0', 05:00:30 INFO - 'XPC_SERVICE_NAME': '0', 05:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:30 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 05:00:30 INFO - Cleaning up... 05:00:30 INFO - Return code: 0 05:00:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:31 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:31 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 05:00:31 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 05:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:31 INFO - 'HOME': '/Users/cltbld', 05:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:31 INFO - 'LOGNAME': 'cltbld', 05:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:31 INFO - 'MOZ_NO_REMOTE': '1', 05:00:31 INFO - 'NO_EM_RESTART': '1', 05:00:31 INFO - 'PAGER': '/bin/cat', 05:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:31 INFO - 'PWD': '/builds/slave/test', 05:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:31 INFO - 'SHELL': '/bin/bash', 05:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:31 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:31 INFO - 'USER': 'cltbld', 05:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:31 INFO - 'XPC_FLAGS': '0x0', 05:00:31 INFO - 'XPC_SERVICE_NAME': '0', 05:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:31 INFO - Downloading/unpacking psutil>=3.1.1 05:00:31 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 05:00:31 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 05:00:31 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 05:00:31 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 05:00:31 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 05:00:31 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 05:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:35 INFO - Installing collected packages: psutil 05:00:35 INFO - Running setup.py install for psutil 05:00:35 INFO - building 'psutil._psutil_osx' extension 05:00:35 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 05:00:35 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 05:00:35 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 05:00:36 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 05:00:36 INFO - building 'psutil._psutil_posix' extension 05:00:36 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 05:00:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:00:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:00:36 INFO - ^ 05:00:36 INFO - 1 warning generated. 05:00:36 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 05:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:36 INFO - Successfully installed psutil 05:00:36 INFO - Cleaning up... 05:00:36 INFO - Return code: 0 05:00:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:36 INFO - 'HOME': '/Users/cltbld', 05:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:36 INFO - 'LOGNAME': 'cltbld', 05:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:36 INFO - 'MOZ_NO_REMOTE': '1', 05:00:36 INFO - 'NO_EM_RESTART': '1', 05:00:36 INFO - 'PAGER': '/bin/cat', 05:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:36 INFO - 'PWD': '/builds/slave/test', 05:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:36 INFO - 'SHELL': '/bin/bash', 05:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:36 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:36 INFO - 'USER': 'cltbld', 05:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:36 INFO - 'XPC_FLAGS': '0x0', 05:00:36 INFO - 'XPC_SERVICE_NAME': '0', 05:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00:36 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 05:00:36 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 05:00:36 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 05:00:36 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 05:00:36 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 05:00:36 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 05:00:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:39 INFO - Installing collected packages: mozsystemmonitor 05:00:39 INFO - Running setup.py install for mozsystemmonitor 05:00:39 INFO - Successfully installed mozsystemmonitor 05:00:39 INFO - Cleaning up... 05:00:39 INFO - Return code: 0 05:00:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:00:39 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')]} 05:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:39 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')]} 05:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:39 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:39 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 05:00:39 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 05:00:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:39 INFO - 'HOME': '/Users/cltbld', 05:00:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:39 INFO - 'LOGNAME': 'cltbld', 05:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:39 INFO - 'MOZ_NO_REMOTE': '1', 05:00:39 INFO - 'NO_EM_RESTART': '1', 05:00:39 INFO - 'PAGER': '/bin/cat', 05:00:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:39 INFO - 'PWD': '/builds/slave/test', 05:00:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:39 INFO - 'SHELL': '/bin/bash', 05:00:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:39 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:39 INFO - 'USER': 'cltbld', 05:00:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:39 INFO - 'XPC_FLAGS': '0x0', 05:00:39 INFO - 'XPC_SERVICE_NAME': '0', 05:00:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:39 INFO - Downloading/unpacking jsonschema==2.5.1 05:00:39 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 05:00:39 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 05:00:39 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 05:00:39 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 05:00:39 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 05:00:39 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 05:00:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:00:42 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 05:00:42 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 05:00:42 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 05:00:42 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 05:00:42 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 05:00:42 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 05:00:42 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:43 INFO - warning: no files found matching '*.txt' 05:00:43 INFO - no previously-included directories found matching 'build' 05:00:43 INFO - no previously-included directories found matching 'dist' 05:00:43 INFO - no previously-included directories found matching '.git*' 05:00:43 INFO - Installing collected packages: jsonschema, functools32 05:00:43 INFO - Running setup.py install for functools32 05:00:43 INFO - warning: no files found matching '*.txt' 05:00:43 INFO - no previously-included directories found matching 'build' 05:00:43 INFO - no previously-included directories found matching 'dist' 05:00:43 INFO - no previously-included directories found matching '.git*' 05:00:43 INFO - Successfully installed jsonschema functools32 05:00:43 INFO - Cleaning up... 05:00:43 INFO - Return code: 0 05:00:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:43 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:43 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 05:00:43 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 05:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:43 INFO - 'HOME': '/Users/cltbld', 05:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:43 INFO - 'LOGNAME': 'cltbld', 05:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:43 INFO - 'MOZ_NO_REMOTE': '1', 05:00:43 INFO - 'NO_EM_RESTART': '1', 05:00:43 INFO - 'PAGER': '/bin/cat', 05:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:43 INFO - 'PWD': '/builds/slave/test', 05:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:43 INFO - 'SHELL': '/bin/bash', 05:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:43 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:43 INFO - 'USER': 'cltbld', 05:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:43 INFO - 'XPC_FLAGS': '0x0', 05:00:43 INFO - 'XPC_SERVICE_NAME': '0', 05:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:00:43 INFO - Cleaning up... 05:00:43 INFO - Return code: 0 05:00:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:43 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:43 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 05:00:43 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 05:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:43 INFO - 'HOME': '/Users/cltbld', 05:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:43 INFO - 'LOGNAME': 'cltbld', 05:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:43 INFO - 'MOZ_NO_REMOTE': '1', 05:00:43 INFO - 'NO_EM_RESTART': '1', 05:00:43 INFO - 'PAGER': '/bin/cat', 05:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:43 INFO - 'PWD': '/builds/slave/test', 05:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:43 INFO - 'SHELL': '/bin/bash', 05:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:43 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:43 INFO - 'USER': 'cltbld', 05:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:43 INFO - 'XPC_FLAGS': '0x0', 05:00:43 INFO - 'XPC_SERVICE_NAME': '0', 05:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:43 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 05:00:43 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 05:00:43 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 05:00:43 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 05:00:43 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 05:00:43 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 05:00:46 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00:46 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 05:00:46 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 05:00:46 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 05:00:46 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 05:00:46 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 05:00:46 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 05:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00:47 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 05:00:47 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 05:00:47 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 05:00:47 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 05:00:47 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 05:00:47 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 05:00:47 INFO - Downloading docopt-0.6.1.tar.gz 05:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:47 INFO - Running setup.py install for blobuploader 05:00:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:47 INFO - Running setup.py install for requests 05:00:48 INFO - Running setup.py install for docopt 05:00:48 INFO - Successfully installed blobuploader requests docopt 05:00:48 INFO - Cleaning up... 05:00:48 INFO - Return code: 0 05:00:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:48 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')]} 05:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:48 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')]} 05:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:48 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:48 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 05:00:48 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 05:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:48 INFO - 'HOME': '/Users/cltbld', 05:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:48 INFO - 'LOGNAME': 'cltbld', 05:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:48 INFO - 'MOZ_NO_REMOTE': '1', 05:00:48 INFO - 'NO_EM_RESTART': '1', 05:00:48 INFO - 'PAGER': '/bin/cat', 05:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:48 INFO - 'PWD': '/builds/slave/test', 05:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:48 INFO - 'SHELL': '/bin/bash', 05:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:48 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:48 INFO - 'USER': 'cltbld', 05:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:48 INFO - 'XPC_FLAGS': '0x0', 05:00:48 INFO - 'XPC_SERVICE_NAME': '0', 05:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:48 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-e9mVBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:48 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-JGKdXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-nyO588-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-HNMWg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-IwM2t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-wl_1ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-X2o3xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-jcyNNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-d9XIBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9Mo1Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-BYDOku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:49 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-EnZG9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:50 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-94RKq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:50 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-mm5AkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:50 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Baw3Kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:50 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Mkr3Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:50 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-VkBQID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:50 INFO - Running setup.py install for manifestparser 05:00:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:50 INFO - Running setup.py install for mozcrash 05:00:50 INFO - Running setup.py install for mozdebug 05:00:51 INFO - Running setup.py install for mozdevice 05:00:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Running setup.py install for mozfile 05:00:51 INFO - Running setup.py install for mozhttpd 05:00:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Running setup.py install for mozinfo 05:00:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Running setup.py install for mozInstall 05:00:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:51 INFO - Running setup.py install for mozleak 05:00:51 INFO - Running setup.py install for mozlog 05:00:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Running setup.py install for moznetwork 05:00:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Running setup.py install for mozprocess 05:00:52 INFO - Running setup.py install for mozprofile 05:00:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Running setup.py install for mozrunner 05:00:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:52 INFO - Running setup.py install for mozscreenshot 05:00:52 INFO - Running setup.py install for moztest 05:00:53 INFO - Running setup.py install for mozversion 05:00:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:53 INFO - Cleaning up... 05:00:53 INFO - Return code: 0 05:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:53 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:53 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 05:00:53 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 05:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:53 INFO - 'HOME': '/Users/cltbld', 05:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:53 INFO - 'LOGNAME': 'cltbld', 05:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:53 INFO - 'MOZ_NO_REMOTE': '1', 05:00:53 INFO - 'NO_EM_RESTART': '1', 05:00:53 INFO - 'PAGER': '/bin/cat', 05:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:53 INFO - 'PWD': '/builds/slave/test', 05:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:53 INFO - 'SHELL': '/bin/bash', 05:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:53 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:53 INFO - 'USER': 'cltbld', 05:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:53 INFO - 'XPC_FLAGS': '0x0', 05:00:53 INFO - 'XPC_SERVICE_NAME': '0', 05:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-7GMsC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:53 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)) 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-v6NJok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-0aG4On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:53 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)) 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-SzsQDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-j8q9gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:53 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)) 05:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:53 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yL02BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-jZ4Onn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ST7Jn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_A9a3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-1CPT6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_bf611-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WdHz0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-dmOmFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:54 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)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-1VbsQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:54 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-D2zLka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:55 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)) 05:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:55 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-iBuLZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:55 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)) 05:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:55 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-esIGNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:55 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)) 05:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:55 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)) 05:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:57 INFO - Downloading blessings-1.6.tar.gz 05:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:57 INFO - Installing collected packages: blessings 05:00:57 INFO - Running setup.py install for blessings 05:00:58 INFO - Successfully installed blessings 05:00:58 INFO - Cleaning up... 05:00:58 INFO - Return code: 0 05:00:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:58 INFO - Reading from file tmpfile_stdout 05:00:58 INFO - Current package versions: 05:00:58 INFO - blessings == 1.6 05:00:58 INFO - blobuploader == 1.2.4 05:00:58 INFO - docopt == 0.6.1 05:00:58 INFO - functools32 == 3.2.3-2 05:00:58 INFO - jsonschema == 2.5.1 05:00:58 INFO - manifestparser == 1.1 05:00:58 INFO - mozInstall == 1.12 05:00:58 INFO - mozcrash == 1.0 05:00:58 INFO - mozdebug == 0.1 05:00:58 INFO - mozdevice == 0.49 05:00:58 INFO - mozfile == 1.2 05:00:58 INFO - mozhttpd == 0.7 05:00:58 INFO - mozinfo == 0.9 05:00:58 INFO - mozleak == 0.1 05:00:58 INFO - mozlog == 3.4 05:00:58 INFO - moznetwork == 0.27 05:00:58 INFO - mozprocess == 0.23 05:00:58 INFO - mozprofile == 0.28 05:00:58 INFO - mozrunner == 6.13 05:00:58 INFO - mozscreenshot == 0.1 05:00:58 INFO - mozsystemmonitor == 0.3 05:00:58 INFO - moztest == 0.7 05:00:58 INFO - mozversion == 1.4 05:00:58 INFO - psutil == 3.1.1 05:00:58 INFO - requests == 1.2.3 05:00:58 INFO - wsgiref == 0.1.2 05:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:58 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 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:58 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 05:00:58 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 05:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:58 INFO - 'HOME': '/Users/cltbld', 05:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:58 INFO - 'LOGNAME': 'cltbld', 05:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:58 INFO - 'MOZ_NO_REMOTE': '1', 05:00:58 INFO - 'NO_EM_RESTART': '1', 05:00:58 INFO - 'PAGER': '/bin/cat', 05:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:58 INFO - 'PWD': '/builds/slave/test', 05:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:58 INFO - 'SHELL': '/bin/bash', 05:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:58 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:58 INFO - 'USER': 'cltbld', 05:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:58 INFO - 'XPC_FLAGS': '0x0', 05:00:58 INFO - 'XPC_SERVICE_NAME': '0', 05:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 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)) 05:00:58 INFO - Cleaning up... 05:00:58 INFO - Return code: 0 05:00:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1100163f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3c3c76380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, '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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 05:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:58 INFO - 'HOME': '/Users/cltbld', 05:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:58 INFO - 'LOGNAME': 'cltbld', 05:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:58 INFO - 'MOZ_NO_REMOTE': '1', 05:00:58 INFO - 'NO_EM_RESTART': '1', 05:00:58 INFO - 'PAGER': '/bin/cat', 05:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:58 INFO - 'PWD': '/builds/slave/test', 05:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:58 INFO - 'SHELL': '/bin/bash', 05:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:00:58 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:58 INFO - 'USER': 'cltbld', 05:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:58 INFO - 'XPC_FLAGS': '0x0', 05:00:58 INFO - 'XPC_SERVICE_NAME': '0', 05:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:59 INFO - Cleaning up... 05:00:59 INFO - Return code: 0 05:00:59 INFO - Running post-action listener: _resource_record_post_action 05:00:59 INFO - Running post-action listener: _start_resource_monitoring 05:00:59 INFO - Starting resource monitoring. 05:00:59 INFO - [mozharness: 2016-11-29 13:00:59.068142Z] Finished create-virtualenv step (success) 05:00:59 INFO - [mozharness: 2016-11-29 13:00:59.069160Z] Running install step. 05:00:59 INFO - Running pre-action listener: _resource_record_pre_action 05:00:59 INFO - Running main action method: install 05:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:59 INFO - Reading from file tmpfile_stdout 05:00:59 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:59 INFO - Reading from file tmpfile_stdout 05:00:59 INFO - Output received: 05:00:59 INFO - Usage: mozinstall [options] installer 05:00:59 INFO - Options: 05:00:59 INFO - -h, --help show this help message and exit 05:00:59 INFO - -d DEST, --destination=DEST 05:00:59 INFO - Directory to install application into. [default: 05:00:59 INFO - "/builds/slave/test"] 05:00:59 INFO - --app=APP Application being installed. [default: firefox] 05:00:59 INFO - mkdir: /builds/slave/test/build/application 05:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:01:32 INFO - Reading from file tmpfile_stdout 05:01:32 INFO - Output received: 05:01:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:01:32 INFO - Running post-action listener: _resource_record_post_action 05:01:32 INFO - [mozharness: 2016-11-29 13:01:32.076979Z] Finished install step (success) 05:01:32 INFO - [mozharness: 2016-11-29 13:01:32.077138Z] Running run-tests step. 05:01:32 INFO - Running pre-action listener: _resource_record_pre_action 05:01:32 INFO - Running main action method: run_tests 05:01:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:32 INFO - 2016-11-29 05:01:32.091 screenresolution[1634:7569] starting screenresolution argv=screenresolution get 05:01:32 INFO - 2016-11-29 05:01:32.094 screenresolution[1634:7569] Display 0: 1600x1200x32@60 05:01:32 INFO - 2016-11-29 05:01:32.100 screenresolution[1635:7571] starting screenresolution argv=screenresolution list 05:01:32 INFO - Available Modes on Display 0 05:01:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:32 INFO - Intel Iris: 05:01:32 INFO - Chipset Model: Intel Iris 05:01:32 INFO - Type: GPU 05:01:32 INFO - Bus: Built-In 05:01:32 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:32 INFO - Vendor: Intel (0x8086) 05:01:32 INFO - Device ID: 0x0a2e 05:01:32 INFO - Revision ID: 0x0009 05:01:32 INFO - Displays: 05:01:32 INFO - Display: 05:01:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:32 INFO - Display Serial Number: 1600x1200 60 05:01:32 INFO - Main Display: Yes 05:01:32 INFO - Mirror: Off 05:01:32 INFO - Online: Yes 05:01:32 INFO - Rotation: Supported 05:01:32 INFO - Return code: 0 05:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:32 INFO - Python 2.7.3 05:01:32 INFO - Return code: 0 05:01:32 INFO - grabbing minidump binary from tooltool 05:01:32 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')]} 05:01:32 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 0x10fe2cd30>, '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 0x10fc93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe24b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01:32 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 05:01:32 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 05:01:32 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 05:01:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:32 INFO - Return code: 0 05:01:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:01:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:01:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:32 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/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 05:01:32 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/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --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 05:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wLqBlBvIXL/Render', 05:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:32 INFO - 'HOME': '/Users/cltbld', 05:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:32 INFO - 'LOGNAME': 'cltbld', 05:01:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:32 INFO - 'MOZ_NO_REMOTE': '1', 05:01:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:32 INFO - 'NO_EM_RESTART': '1', 05:01:32 INFO - 'PAGER': '/bin/cat', 05:01:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:32 INFO - 'PWD': '/builds/slave/test', 05:01:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:32 INFO - 'SHELL': '/bin/bash', 05:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FNKcdhgMWX/Listeners', 05:01:32 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 05:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:32 INFO - 'USER': 'cltbld', 05:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:32 INFO - 'XPC_FLAGS': '0x0', 05:01:32 INFO - 'XPC_SERVICE_NAME': '0', 05:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:32 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/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 05:01:32 INFO - mozversion application_buildid: 20161129024026 05:01:32 INFO - mozversion application_changeset: f8107cf961444a8d7fdc2c0f446238af9893f875 05:01:32 INFO - mozversion application_display_name: Nightly 05:01:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:32 INFO - mozversion application_name: Firefox 05:01:32 INFO - mozversion application_remotingname: firefox 05:01:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:01:32 INFO - mozversion application_vendor: Mozilla 05:01:32 INFO - mozversion application_version: 53.0a1 05:01:32 INFO - mozversion platform_buildid: 20161129024026 05:01:32 INFO - mozversion platform_changeset: f8107cf961444a8d7fdc2c0f446238af9893f875 05:01:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:01:32 INFO - mozversion platform_version: 53.0a1 05:01:32 INFO - using testdate: 1480424492 05:01:32 INFO - actual date: 1480424492 05:01:32 INFO - starting webserver on 'localhost:49244' 05:01:32 INFO - SUITE-START | Running 2 tests 05:01:32 INFO - TEST-START | dromaeo_css 05:01:32 INFO - operating with platform_type : mac_ 05:01:32 INFO - Initialising browser for dromaeo_css test... 05:01:32 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp6oMNQm/profile http://localhost:49244/getInfo.html) 05:01:36 INFO - PROCESS | 1645 | 2016-11-29 05:01:36.013 plugin-container[1647:7743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 05:01:36 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:36 INFO - PROCESS | 1645 | 2016-11-29 05:01:36.017 plugin-container[1647:7743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 05:01:36 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:36 INFO - PROCESS | 1645 | 2016-11-29 05:01:36.017 plugin-container[1647:7743] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:36 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 05:01:36 INFO - PROCESS | 1645 | colorDepth:24 05:01:36 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 05:01:36 INFO - PROCESS | 1645 | __metrics 05:01:36 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 05:01:36 INFO - TEST-INFO | 1645: exit 0 05:01:36 INFO - Browser initialized. 05:01:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:36 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp6oMNQm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:39 INFO - PROCESS | 1648 | 2016-11-29 05:01:39.534 plugin-container[1649:7863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 05:01:39 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:39 INFO - PROCESS | 1648 | 2016-11-29 05:01:39.535 plugin-container[1649:7863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 05:01:39 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:39 INFO - PROCESS | 1648 | 2016-11-29 05:01:39.535 plugin-container[1649:7863] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:49 INFO - PROCESS | 1648 | RSS: Main: 242434048 05:01:49 INFO - PROCESS | 1648 | 05:02:38 INFO - PROCESS | 1648 | 1480424558765 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] 05:04:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:04:55 INFO - PROCESS | 1648 | RSS: Main: 251453440 05:04:55 INFO - PROCESS | 1648 | 05:07:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:07:58 INFO - PROCESS | 1648 | RSS: Main: 262725632 05:07:58 INFO - PROCESS | 1648 | 05:11:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:11:01 INFO - PROCESS | 1648 | RSS: Main: 268120064 05:11:01 INFO - PROCESS | 1648 | 05:14:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:14:03 INFO - PROCESS | 1648 | RSS: Main: 267280384 05:14:03 INFO - PROCESS | 1648 | 05:17:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:17:07 INFO - PROCESS | 1648 | RSS: Main: 272072704 05:17:07 INFO - PROCESS | 1648 | 05:20:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:20:10 INFO - PROCESS | 1648 | RSS: Main: 274817024 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | __start_tp_report 05:20:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 05:20:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 05:20:10 INFO - PROCESS | 1648 | |i|pagename|runs| 05:20:10 INFO - PROCESS | 1648 | |0;dojo.html;3001.579778830964;3400.2859399526096;3415.9316813663727;3449.9655003449957;3454.980780669703;4206.684498662605;4602.58576728094;5588.273524441827;5696.829095127143;5731.627444216123;5352.518273355407;5606.55147588193;5701.828945131652;5706.1440783882435;5786.797462088828;4604.654650901179;5077.771500282496;5198.116320225552;5209.1404918188455;5269.394019687747;5238.188080847455;5268.946210757845;5281.287026251464;5285.180796976456;5325.227841962933;5111.591072714174;5247.16045432729;5260.815871444519;5274.7626356814035;5291.920621190685;5128.692278463304;5161.045206636784;5187.118189907725;5192.766325515361;5209.6874187548865;5120.718360490171;5122.769475373617;5124.871878203037;5151.665141765773;5168.173092305213;5031.496850314976;5327.9733601331745;5601.159826026088;5670.68811215383;5677.716114194273;6230.719617617218;6231.096491008779;6236.158118654014;6236.532260080512;6238.781642642532;6234.470070044052;6235.532335074888;6261;6270.278917924452;6302.55882088254;5683.54531637468;5684.971575142181;5777.277840269966;5905.940940590625;5921.052631578969;6201.782937597162;6207.441330280206;6244.1570387997035;6245;6252.437280644673;6161.075838624152;6166.982447896047;6172.753089876444;6186.81439556814;6194.34959329273;6223.408776166257;6249.96875015622;6252.781152659635;6255.937440625627;6256.937430625727;6099.329073801879;6125.84685382869;6127.769884593299;6132.080187971751;6148.047052706837;6140.197513349931;6142.314611233224;6161.4146656067605;6205.47253483459;6270.090836828635;4866.78099485524;4895.241237608106;4910.361652985089;4946;4962.280469331927;4243.2998555238655;4425.291953287523;4460.375547423363;4468.642508599304;4469.620082292977;5549.112142057252;5720.170575266563;5807.506361959281;5849.824505264849;5881.382454842265;6233.065040243909;6244.406781355764;6258.3428739982555;6262.3111457739615;6292.05619157121;5808.970955145197;5884.7940322088525;5896.115582662549;5902.314750313473;5928.970355148197;5277.287566178594;5342.54588359986;5371.221172929982;5423.972880135574;5433.157860531544;5701.315842098808;5855.765769369177;5999.1601175836295;6233.719482623384;6259.937400626027;5210.947890521046;6246.1255424239735;6269.27286630299;6282.937170628418;6295.622262664072;5223.45153758865;6276.6547839869245;6293.527985401205;6346.777862774966;6355.3326900674765;5248.580113590827;6072.574919755575;6127.84680382994;6135.447809697149;6254.687265636791;6105.732692890381;6198.132261483486;6200.255969283715;6201.720922558406;6269.279032911091;6094.8171554853425;6108.114323423168;6151.046587778901;6160.291566469913;6170.7223174958135;5123.974380128076;5361.812336568201;6012.789552365646;6099.542534309856;6170.475509581556;5697.686627235542;5974.163617093697;5983.401659833982;5996.820095397145;6107.633541987318;6032.909506357496;6037.516998640186;6039.78860739872;6127.264688495017;6162.907556386568;5828.184054232326;5904.350521442552;5926.733297001559;5927.377625349276;5932.139839723302 05:20:10 INFO - PROCESS | 1648 | |1;ext.html;493.4917035453483;519.1926554208206;520.6901893373441;529.7563120964356;546.0307953382745;36200.45699314509;38564.61435385652;38689.61310386896;38700.41949370758;38722.41916371257;38913.22173556527;39539.20921581567;39584.40623390644;39587.802060989656;39688.80155599225;8463.873041904377;8669.869951950726;9149.725508234764;9425.528723563828;11101.44492775362;11100.278481898651;11334.433278336091;11341.149413793957;11351.886481135165;11379.089672826154;11338.60314888979;11362.37506937118;11374.71563210918;11376.033037191808;11393;11290.491927863224;11325.943370283096;11351.716207094847;11370.431478426046;11386.089112871034;9057.139571740674;9677.935427103013;10288.176945844314;11235.269707468988;11275.293929456953;31182.22044448884;38532.42201366982;38708.41937370942;38880.22239555221;38882.61117388818;3330.417176994041;3516.2791627716256;3586.910327241814;3749.193923306475;3972.344563147077;29156.562651560245;29208;29582.964596239242;29794.55308170379;30071.646775895137;2829.3775369418695;2844.5022121128927;2845.6585209774958;2856.6714827794654;2968.0650595062702;306675;376913;378062;382850;385428.0728596367;53810.461895381326;54291;54484.45515544794;54567;54680;57311.71344143336;57992.42007579954;58013.12980305299;58119.70940145357;58354.416455835744;14556;14792.630184245265;14952.925235373754;14971.251437428085;14991.400343986119;9854.162396196403;10163.390196588229;10639.893601064045;11682.824757628643;11707.297562146128;15367;15401.845981540266;15412.383504659912;15415.229238538028;15511.069335839886;6822.488313376517;6911.101556797685;6919.342662447059;6932.618705971119;6944.548604340702;838.6623335779421;918.5682729117304;1000.5935724582391;1005.3113617172261;1007.3351587951917;24487.387815304402;28591.42817143687;28698.856505717336;29051.12846614605;29548.261293467836;979.7354714227118;1090.4438736244572;1104.8895110488886;1105.5135740274254;1108.257467496779;12585.496580136693;14654.413823446943;14829.184394858386;14963.027403218755;15010.699786004438;489809;492931;494814;497438;500418;52688.473115269124;53051;53196.202056969174;53569.1964620531;53738.731306343216;1328.3690247132536;1364.1405914273944;1373.2378529915993;1377.1117629129155;1377.7313423882447;28491.002814902207;28523.144305670863;28571.857140714164;29284.560731588615;29513.409731805674;45993.54006460027;46332.07335853197;46409.071818564116;46514;46541.30188047114;417061;422020;423622;425403;425607.231783047;24507.50984980326;25224.747752522973;25389.61915571231;26099.608505872806;36999.44500832436;44160.33759493546;44306;45134.32298515591;45431.31853022141;45620.54379456296;31385;34964;36781.63218367782;37007.259854802214;37063;36145.27709445744;36184.45723314205;36909.446358305184;37083.6291637091;37240.441393378576;35927.82036089907;36053;36695;36783.81608091942;37365.252694945404;26091.08681196149;31444.899428519893;32334.35331293408;32631.510527341634;32816.50775238421;19751.40745777629;23157.842107894874;23480.412989675395;24843.62734558947;24966.126185584144 05:20:10 INFO - PROCESS | 1648 | |2;jquery.html;316.32938171077313;338.1478673742169;371.8902923637527;384.1989451992595;400.81362166592527;18673.439796806084;19023.809761902376;19129.808701912993;19776.90111549444;19936.50158746032;15692.529224123295;18069.7289540657;18379.448616541526;18905.90547047263;18955.905220473913;5627.2684551008315;5904.852378690545;5920.496757775572;5921.851953701149;5935.940640593603;5580.972095139518;5823.272090988627;5857.589968702193;5858.41415858415;5862.472377486026;5333.63992181993;5769.221155144065;5789.623674461168;5794.130880367937;5826.446487583673;4260.190563792885;4884.755762211875;5539.944600554003;5795.913061304083;5810.912836307459;4264.424302719124;4990.925136122961;5044.6973181608855;5063.645544811865;5079.263506791494;23488.647670284958;23731.288061358187;23848.523029539487;24156.275311740676;24180.39549011271;2175.9020844062056;2291.014863608648;2414.985706003483;2431.088341871798;2716.212298433452;14528.927355363261;14659.193744344057;14859.554213373616;14993.32530036151;15160.31778569967;1794.371969810569;1795.721663142215;1796.4610616815035;1823.5714607067462;1899.4586542835225;95178.52410737971;102174.99999999926;103194;103639;104787.4760626192;8655.913440865637;9824.901750982399;10029.348092373972;10105.545250463747;10289.3311934724;12639.115261931576;12645.74708505843;12671;12671;12680.429380677891;7756.263155000379;7883.84232315362;7986.161453047463;8083.070446898682;8096.878546821802;7862.208915834911;7987.84024319522;8003.959980200062;8085.919140808634;8308.5430301334;5215.687058776485;6348.8412789679705;6356.2054743157105;6372.968135159387;6383.521235907326;1548.5741421109285;1754.166770783893;1754.2895127473394;1786.2944137065786;1801.6126532795513;725.9020032295573;871.7602659268625;974.5176137811687;1000.9599616015278;1028.93311934724;20374.490637734176;20819.271325503836;20911.47721306949;20993.47516312074;21318.41502999121;896.9955150224207;902.1474706402518;921.1249313152532;940;953.7997020625746;10458.111060559786;10694.625688101034;11281;11284.21010529255;11287.492062857027;415841;454888;503003.4849825727;511155.8884411183;516611;69981.65009175023;82248.58875705703;82585.76121358185;101331.49334253282;106049.99999999846;962.1148543340144;981.617169303972;990.2474119669104;993.2649839119;996.5798736800031;12676.492940282285;18120.18459169367;18589.977551234813;18641.067946602885;18880.43358699241;34074.65925340814;44920;49652;50438.74780626073;52469.95060098853;76900.2309976893;77247;77689.61155194188;77948;80548;7753.883691744742;7868.960655196687;7987.9600601998945;8070.798230044295;8212.219839115274;10270.794584108426;11156.768646270615;12371.000775486582;12515;12784.61646150617;8965.641374344954;9506.952465237906;10406.323588966692;10470;10976.835347469634;10449.58201671955;10647.361158330215;10716;10959.56161753521;10966.835497467704;9579.760505987406;10590.735231618963;11148.996590306911;11195.2723073;11309.151813614177;7883.172266912121;8034.156413576724;8083.3533317333295;8227.670893164206;8353.791155221168;9783.315167938175;10682.73293167677;10961.671149865517;10975.560977561128;11029.889701103208 05:20:10 INFO - PROCESS | 1648 | |3;mootools.html;1472.308015232841;1481.1705444950828;1677.8825482216241;1784.232779904642;1811.8188181181874;7484.064491938508;7711.807204819868;7965.84068318634;8390.160983901607;8441.4253652636;7919.485233459821;8213.096559378464;8264.09094999551;8347.248747612713;8383.580820958943;2192.0464597899927;2218.1460137846966;2230.966535501969;2232.531168454618;2232.810211132056;2280.293109136173;2311.514581937795;2325.3140323604493;2328.557574060923;2336.9883150584224;1920.7310976463307;2269.375921621559;2282.2468585366873;2299.9655005174936;2303.8387312888103;1996.17158879065;2190.386691726303;2251.1783199132283;2258.7854153855355;2286.622707253301;2271.216430331533;2280.8289378296695;2304.781045800646;2306.6770652108717;2307.065638416444;8274.917250827475;8299.875501867476;8347.666093356258;8392.370572207108;8425.831483370359;4956.851294461172;5076.44159142494;5107.4892510749;5112.846614601568;5115.335006449175;2970.4653162430754;3225.645179030305;3528.347255757693;3532.275883443888;3536.752427330088;939.262678797141;1019.2763138171907;1076.7146706122883;1084.9728756781064;1085.8154113800672;10566.890476499933;10588.205884558689;10606.15150787935;10699.679009629723;10728.946355268252;4441.689081764245;4546.727196368229;4612.492625811158;4652.976735116303;4675.041616468616;9251;9274.397164184307;9317.953410232905;9344.018878017847;9352.111549402796;5091.770870310845;5095.420419669914;5098.949010509921;5108.9744551277745;5110.131277682803;4117.5882411758585;4401.2517871961845;4635.096156249499;4666.533346665306;4684.391029166187;3717.8436506825974;3726.869559565402;3800.562935262425;3827.7129215308964;3839.176391214151;1259.238160912653;1260.5966090851023;1264.3551788587702;1272.6500212441654;1274.3182397417522;797.4298376660715;822.3256929318021;864.312871267344;908.5321059654283;940.6660635474308;5570.972145139248;5571.9442805572235;5706.572007099401;5825.621334613236;5849.1226316052935;968.1528662420383;994.3536701144315;1015.6496008876926;1026.3995562595817;1046.1787496814964;3063;3778.677462887967;3826.1582451860545;3868.903277418087;3957.2876882161486;9768.853467197998;9986.20110391167;10062.39625622465;10067.942865999112;10092.44491552957;9920.305578609572;9940.900590994142;9959.352642078242;10049.69850904474;10065.748356291151;7685.5772932488135;7732.530819144345;7871.370290376756;7873.094594121635;7895.802604934807;4656.324832899279;5523.8066667666435;5655.604107712421;5688.232088667977;5718.685472299064;9269.44383337002;9298.442093474143;9372.437653740797;9433.57548910287;9455.952720236355;7177.282271772781;9315.86026209594;9326.393784403992;9416.199623031845;9425.434473931586;6576.80269591913;6772.55978361405;6926.688299026455;7073.151221853204;7227.2772722729405;7695.4997925134685;7923.247291507298;7944.285014348737;7960.721374751856;7973.322267607318;5483.4516548344845;6470.547061705635;7044;7142.071530701183;7187.209406965125;6890.552114112566;6934.722611095702;6943.756968506078;6949.791506254821;6989.33601307875;6495.408624886933;6552.148220731465;6611.7271001897525;6619.238787539494;6650.501212408992;4749.145153872336;4807.663463557517;4838.225883858566;4850;4918.237673160523;4823.614110871192;4920.975395123001;4922.1632322505975;4930.2604609307455;4947.406311242674 05:20:10 INFO - PROCESS | 1648 | |4;prototype.html;292.55093431582526;293.2214969256624;309.97520198384126;312.4750419295584;314.0750489807518;5239.580833533318;5440.537554307879;5458.672479651219;5537.667739935607;5539.446055394458;4406.69153159279;5041.77312020959;5261.842144735654;5323.840284791453;5375.650582712121;3629.60074391818;4168.145530166324;4174.895627609304;4178.164367126572;4189.539150693421;3840.36633955397;4044.024563998944;4094.263032654121;4167.979160104211;4194.727342722713;3571.839267232968;4070.9185816283657;4129.876103716878;4144.647704945083;4148.755373387987;3651.981740091309;4084.6323830855367;4108.979455102721;4126.690498212631;4133.834646614132;3586.7130629549833;3605.0987253186704;3633.4913112164318;3635.4365073413665;3665.7433979621437;5101.974490127563;5227.60792940531;5290.656107353048;5300.072487314705;5310.548603368679;4189.973456503176;4734.715917044989;4750.667453278272;4765.022515655998;4995.700257984533;4204.456303936136;4593.632509399274;5443.591730620219;5590.608657393984;5730.512906402961;3277.852496637657;3352.413327667673;3563.4832949222478;3813.103920578662;3957.208558288325;5155.40712818027;5902.491441137542;5945.710901302986;5957.523398128139;5993.640381577119;3392.338493993697;3401.9489707654407;4021.6380525752825;4038.1519880824767;4042.7574345538774;3863.362545180014;4123.463949686581;4124.546299907008;4129.215449064668;4157.584241575879;3737.8131093445218;4410.757408342508;4675.29870519418;4707.458642256116;4707.835225767081;5091.439941606421;5739.196512488255;5740.913886291709;5742.770289188469;5773.740181691834;1170.169179882276;1271.961841144767;1287.2557684337776;1290.9027326772357;1349.743548725739;908.2416182487565;913.5021413329752;914.6752902719438;914.9130832570895;921.3136213520836;4729.597984171315;4909.25721368916;5156.329677141947;5228.581713462914;5235.816746413857;4285.571442855689;4743.83396581118;5439.322182922762;5546.55627549795;5580.91628625571;4157;4351.630111440563;4496.258117410591;4523.457185137804;4560.143447228757;2454.742252063543;2564.3076369380165;2686.556718141524;2777.2084955787504;2872.7503535961923;5046.369203849519;5249.107651699221;5335.33308336458;5356.848442457049;6128.540359473057;5041.697498150123;6046.035038612468;6099.969500152471;6110.786122485781;6112.572119951649;5392;5507.862303442366;6173.7401193176565;6179.969100154651;6219.253689557282;4906.607471402351;5614.108734002074;5871.6770577618645;5944.256967879015;5974.342822289632;4605.585497305258;4926.965337279212;4995.15082435994;5870.765169393348;5971.596190171561;5143.897122057613;5379.305518761577;5907.4683278505145;6083.270007599116;6152.323244443197;3903.726739128234;3931.6854651627227;3943.881683549498;3946.8421263149153;3947.5591409134886;4969.266903203296;5966.850828729316;5981.820545383645;6005.069214271794;6022.435545255692;5399.408284023612;5740.454656807597;5749.367569567428;5797.8550536236935;5800.796972105956;4779.900622856832;4954.900901982012;5721.856953576027;5731.054376027909;5772.3650398456975;4786.904261914672;4872.707637541759;5241.292425522505;5424.349078110652;5631.436856314336;4563.960894608362;4770.570648641639;4947.208446648519;5674.801381951612;5724.885502290014;4858.562729354375;5361.758720857649;5616.5787565931905;5621.971890140523;5653.434656534314 05:20:10 INFO - PROCESS | 1648 | |5;yui.html;646.4408286831889;660.3594513322076;718.2566044144312;751.4589495563195;819.7991492084439;14275.214863182533;15097.471588494405;15198.772018419733;15218.391264349413;15250;13256.138351007177;13716.0398772086;13835.03154779166;13854.238016909067;13855;822.0710597025353;829.6308142876422;837.8282452097305;839.9244068033876;839.9706010289641;668.7793028300673;688.1398252184769;805.7179987004531;847.7075408983891;849.0660273698943;872.4808738800403;875.6497401039571;903.5933829776594;904.2991681446865;906.4153620914507;896.5203616065406;896.8878890138733;898.6315610599688;899.7615631857531;900.5362238447204;653.1769969838039;656.4190691238289;666.2271764752863;667.2326824152216;668.6589839182003;14820.184889831055;14882.255887205596;14904.18027008514;14943.59164046609;15137.318820653098;10315.43265120418;13918.304084795822;14219.928900355535;14344.211068391234;14595.489157879423;11571.652850414417;11802.763944721144;11919.284842909366;11994.88005119955;12217.961473274694;3350.949735753966;3710.684591809699;3727.683146932514;3888.8055597220027;3902.5316961964745;96402.9999999993;116433;118434;121580.56838863173;123040.1543976841;9213.539323033821;10835.079018283534;10890.564377424815;10953.34279943206;11140.944295278472;18652.44042678722;22112.44718882041;22191.66712499314;22263.777362226494;22282.665760013613;5926;6192.287886893065;7227.747028853965;7366.8526629467115;7513.47405681608;3492.860285588599;3524.2599054198904;4044.7168698190844;4167.083241686824;4288.785560721952;2974.4348573770912;3365.74756893234;3438.1810655668132;3464.566929133858;3498.9300213996084;579.9797007104731;624.3350831364583;627.190923708411;638.1990601794718;642.4250295985067;520.6987697994732;605.3250625552478;649.6297110646981;662.4303099334569;675.4090171100287;7254.383377412979;8852.513111778786;8873.73378798637;9000.68497602594;9235.07649235071;529.7907326605969;573.3034363248673;606.1271768653125;637.8118455055769;678.4063944048958;3991.401289806552;4029.7963119319625;4322.3516472528745;4378.299472084451;4569.405977222939;99662;99735.50132249437;100630.4968475153;103305.96694033114;103345.48327258466;73803.89294160593;75772;76884.61557692176;83041.16958830455;88293;77006;78195;79279;79781;80067;7325.597092159983;9569.952150239204;9652.27607929394;9658.3722058066;9706.223502119954;42150.578494214664;47513.762431189;47562;50400.49599504105;51301.23048154206;59257;66796;71802.92295615465;73564.63217683877;75011;12664.936675316565;12760.32033272986;12796.10427270082;12909.419076141408;13035;10722.839157412489;13031.464390824025;13498.52755153565;13646.454141834216;14018.579442616738;12946.741065178832;13325.800112998506;13473.730525389634;13586.86413135856;13637.522686705917;12671.80992285135;12745.362731863555;12805.359732013176;12912.677183070498;13571.52499662507;13232.40454179545;13299.66750831237;13326.86673133295;13355.131916425402;13697.589072327846;9870.851937220803;10703.571857125628;10725.927407259524;10828.458577071273;11480.540778368771;10632.936606742815;10774.353538787698;11301.773964520498;11320.320780753182;11339 05:20:10 INFO - PROCESS | 1648 | __end_tp_report 05:20:10 INFO - PROCESS | 1648 | __start_cc_report 05:20:10 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,379 05:20:10 INFO - PROCESS | 1648 | __end_cc_report 05:20:10 INFO - PROCESS | 1648 | __startTimestamp1480425610245__endTimestamp 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | ------- Summary: start ------- 05:20:10 INFO - PROCESS | 1648 | Number of tests: 6 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5682.49 Median:5905.94 stddev:661.59 (11.2%) stddev-sans-first:629.30 05:20:10 INFO - PROCESS | 1648 | Values: 3001.6 3400.3 3415.9 3450.0 3455.0 4206.7 4602.6 5588.3 5696.8 5731.6 5352.5 5606.6 5701.8 5706.1 5786.8 4604.7 5077.8 5198.1 5209.1 5269.4 5238.2 5268.9 5281.3 5285.2 5325.2 5111.6 5247.2 5260.8 5274.8 5291.9 5128.7 5161.0 5187.1 5192.8 5209.7 5120.7 5122.8 5124.9 5151.7 5168.2 5031.5 5328.0 5601.2 5670.7 5677.7 6230.7 6231.1 6236.2 6236.5 6238.8 6234.5 6235.5 6261.0 6270.3 6302.6 5683.5 5685.0 5777.3 5905.9 5921.1 6201.8 6207.4 6244.2 6245.0 6252.4 6161.1 6167.0 6172.8 6186.8 6194.3 6223.4 6250.0 6252.8 6255.9 6256.9 6099.3 6125.8 6127.8 6132.1 6148.0 6140.2 6142.3 6161.4 6205.5 6270.1 4866.8 4895.2 4910.4 4946.0 4962.3 4243.3 4425.3 4460.4 4468.6 4469.6 5549.1 5720.2 5807.5 5849.8 5881.4 6233.1 6244.4 6258.3 6262.3 6292.1 5809.0 5884.8 5896.1 5902.3 5929.0 5277.3 5342.5 5371.2 5424.0 5433.2 5701.3 5855.8 5999.2 6233.7 6259.9 5210.9 6246.1 6269.3 6282.9 6295.6 5223.5 6276.7 6293.5 6346.8 6355.3 5248.6 6072.6 6127.8 6135.4 6254.7 6105.7 6198.1 6200.3 6201.7 6269.3 6094.8 6108.1 6151.0 6160.3 6170.7 5124.0 5361.8 6012.8 6099.5 6170.5 5697.7 5974.2 5983.4 5996.8 6107.6 6032.9 6037.5 6039.8 6127.3 6162.9 5828.2 5904.4 5926.7 5927.4 5932.1 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:57200.79 Median:19757.37 stddev:114433.51 (579.2%) stddev-sans-first:114675.32 05:20:10 INFO - PROCESS | 1648 | Values: 493.5 519.2 520.7 529.8 546.0 36200.5 38564.6 38689.6 38700.4 38722.4 38913.2 39539.2 39584.4 39587.8 39688.8 8463.9 8669.9 9149.7 9425.5 11101.4 11100.3 11334.4 11341.1 11351.9 11379.1 11338.6 11362.4 11374.7 11376.0 11393.0 11290.5 11325.9 11351.7 11370.4 11386.1 9057.1 9677.9 10288.2 11235.3 11275.3 31182.2 38532.4 38708.4 38880.2 38882.6 3330.4 3516.3 3586.9 3749.2 3972.3 29156.6 29208.0 29583.0 29794.6 30071.6 2829.4 2844.5 2845.7 2856.7 2968.1 306675.0 376913.0 378062.0 382850.0 385428.1 53810.5 54291.0 54484.5 54567.0 54680.0 57311.7 57992.4 58013.1 58119.7 58354.4 14556.0 14792.6 14952.9 14971.3 14991.4 9854.2 10163.4 10639.9 11682.8 11707.3 15367.0 15401.8 15412.4 15415.2 15511.1 6822.5 6911.1 6919.3 6932.6 6944.5 838.7 918.6 1000.6 1005.3 1007.3 24487.4 28591.4 28698.9 29051.1 29548.3 979.7 1090.4 1104.9 1105.5 1108.3 12585.5 14654.4 14829.2 14963.0 15010.7 489809.0 492931.0 494814.0 497438.0 500418.0 52688.5 53051.0 53196.2 53569.2 53738.7 1328.4 1364.1 1373.2 1377.1 1377.7 28491.0 28523.1 28571.9 29284.6 29513.4 45993.5 46332.1 46409.1 46514.0 46541.3 417061.0 422020.0 423622.0 425403.0 425607.2 24507.5 25224.7 25389.6 26099.6 36999.4 44160.3 44306.0 45134.3 45431.3 45620.5 31385.0 34964.0 36781.6 37007.3 37063.0 36145.3 36184.5 36909.4 37083.6 37240.4 35927.8 36053.0 36695.0 36783.8 37365.3 26091.1 31444.9 32334.4 32631.5 32816.5 19751.4 23157.8 23480.4 24843.6 24966.1 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:29767.54 Median:378.04 stddev:80262.41 (21230.9%) stddev-sans-first:80457.11 05:20:10 INFO - PROCESS | 1648 | Values: 316.3 338.1 371.9 384.2 400.8 18673.4 19023.8 19129.8 19776.9 19936.5 15692.5 18069.7 18379.4 18905.9 18955.9 5627.3 5904.9 5920.5 5921.9 5935.9 5581.0 5823.3 5857.6 5858.4 5862.5 5333.6 5769.2 5789.6 5794.1 5826.4 4260.2 4884.8 5539.9 5795.9 5810.9 4264.4 4990.9 5044.7 5063.6 5079.3 23488.6 23731.3 23848.5 24156.3 24180.4 2175.9 2291.0 2415.0 2431.1 2716.2 14528.9 14659.2 14859.6 14993.3 15160.3 1794.4 1795.7 1796.5 1823.6 1899.5 95178.5 102175.0 103194.0 103639.0 104787.5 8655.9 9824.9 10029.3 10105.5 10289.3 12639.1 12645.7 12671.0 12671.0 12680.4 7756.3 7883.8 7986.2 8083.1 8096.9 7862.2 7987.8 8004.0 8085.9 8308.5 5215.7 6348.8 6356.2 6373.0 6383.5 1548.6 1754.2 1754.3 1786.3 1801.6 725.9 871.8 974.5 1001.0 1028.9 20374.5 20819.3 20911.5 20993.5 21318.4 897.0 902.1 921.1 940.0 953.8 10458.1 10694.6 11281.0 11284.2 11287.5 415841.0 454888.0 503003.5 511155.9 516611.0 69981.7 82248.6 82585.8 101331.5 106050.0 962.1 981.6 990.2 993.3 996.6 12676.5 18120.2 18590.0 18641.1 18880.4 34074.7 44920.0 49652.0 50438.7 52470.0 76900.2 77247.0 77689.6 77948.0 80548.0 7753.9 7869.0 7988.0 8070.8 8212.2 10270.8 11156.8 12371.0 12515.0 12784.6 8965.6 9507.0 10406.3 10470.0 10976.8 10449.6 10647.4 10716.0 10959.6 10966.8 9579.8 10590.7 11149.0 11195.3 11309.2 7883.2 8034.2 8083.4 8227.7 8353.8 9783.3 10682.7 10961.7 10975.6 11029.9 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5380.23 Median:5093.60 stddev:2941.81 (57.8%) stddev-sans-first:2935.40 05:20:10 INFO - PROCESS | 1648 | Values: 1472.3 1481.2 1677.9 1784.2 1811.8 7484.1 7711.8 7965.8 8390.2 8441.4 7919.5 8213.1 8264.1 8347.2 8383.6 2192.0 2218.1 2231.0 2232.5 2232.8 2280.3 2311.5 2325.3 2328.6 2337.0 1920.7 2269.4 2282.2 2300.0 2303.8 1996.2 2190.4 2251.2 2258.8 2286.6 2271.2 2280.8 2304.8 2306.7 2307.1 8274.9 8299.9 8347.7 8392.4 8425.8 4956.9 5076.4 5107.5 5112.8 5115.3 2970.5 3225.6 3528.3 3532.3 3536.8 939.3 1019.3 1076.7 1085.0 1085.8 10566.9 10588.2 10606.2 10699.7 10728.9 4441.7 4546.7 4612.5 4653.0 4675.0 9251.0 9274.4 9318.0 9344.0 9352.1 5091.8 5095.4 5098.9 5109.0 5110.1 4117.6 4401.3 4635.1 4666.5 4684.4 3717.8 3726.9 3800.6 3827.7 3839.2 1259.2 1260.6 1264.4 1272.7 1274.3 797.4 822.3 864.3 908.5 940.7 5571.0 5571.9 5706.6 5825.6 5849.1 968.2 994.4 1015.6 1026.4 1046.2 3063.0 3778.7 3826.2 3868.9 3957.3 9768.9 9986.2 10062.4 10067.9 10092.4 9920.3 9940.9 9959.4 10049.7 10065.7 7685.6 7732.5 7871.4 7873.1 7895.8 4656.3 5523.8 5655.6 5688.2 5718.7 9269.4 9298.4 9372.4 9433.6 9456.0 7177.3 9315.9 9326.4 9416.2 9425.4 6576.8 6772.6 6926.7 7073.2 7227.3 7695.5 7923.2 7944.3 7960.7 7973.3 5483.5 6470.5 7044.0 7142.1 7187.2 6890.6 6934.7 6943.8 6949.8 6989.3 6495.4 6552.1 6611.7 6619.2 6650.5 4749.1 4807.7 4838.2 4850.0 4918.2 4823.6 4921.0 4922.2 4930.3 4947.4 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4523.93 Median:4951.05 stddev:1405.05 (28.4%) stddev-sans-first:1372.63 05:20:10 INFO - PROCESS | 1648 | Values: 292.6 293.2 310.0 312.5 314.1 5239.6 5440.5 5458.7 5537.7 5539.4 4406.7 5041.8 5261.8 5323.8 5375.7 3629.6 4168.1 4174.9 4178.2 4189.5 3840.4 4044.0 4094.3 4168.0 4194.7 3571.8 4070.9 4129.9 4144.6 4148.8 3652.0 4084.6 4109.0 4126.7 4133.8 3586.7 3605.1 3633.5 3635.4 3665.7 5102.0 5227.6 5290.7 5300.1 5310.5 4190.0 4734.7 4750.7 4765.0 4995.7 4204.5 4593.6 5443.6 5590.6 5730.5 3277.9 3352.4 3563.5 3813.1 3957.2 5155.4 5902.5 5945.7 5957.5 5993.6 3392.3 3401.9 4021.6 4038.2 4042.8 3863.4 4123.5 4124.5 4129.2 4157.6 3737.8 4410.8 4675.3 4707.5 4707.8 5091.4 5739.2 5740.9 5742.8 5773.7 1170.2 1272.0 1287.3 1290.9 1349.7 908.2 913.5 914.7 914.9 921.3 4729.6 4909.3 5156.3 5228.6 5235.8 4285.6 4743.8 5439.3 5546.6 5580.9 4157.0 4351.6 4496.3 4523.5 4560.1 2454.7 2564.3 2686.6 2777.2 2872.8 5046.4 5249.1 5335.3 5356.8 6128.5 5041.7 6046.0 6100.0 6110.8 6112.6 5392.0 5507.9 6173.7 6180.0 6219.3 4906.6 5614.1 5871.7 5944.3 5974.3 4605.6 4927.0 4995.2 5870.8 5971.6 5143.9 5379.3 5907.5 6083.3 6152.3 3903.7 3931.7 3943.9 3946.8 3947.6 4969.3 5966.9 5981.8 6005.1 6022.4 5399.4 5740.5 5749.4 5797.9 5800.8 4779.9 4954.9 5721.9 5731.1 5772.4 4786.9 4872.7 5241.3 5424.3 5631.4 4564.0 4770.6 4947.2 5674.8 5724.9 4858.6 5361.8 5616.6 5622.0 5653.4 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:20194.51 Median:23158.83 stddev:29693.87 (128.2%) stddev-sans-first:29740.89 05:20:10 INFO - PROCESS | 1648 | Values: 646.4 660.4 718.3 751.5 819.8 14275.2 15097.5 15198.8 15218.4 15250.0 13256.1 13716.0 13835.0 13854.2 13855.0 822.1 829.6 837.8 839.9 840.0 668.8 688.1 805.7 847.7 849.1 872.5 875.6 903.6 904.3 906.4 896.5 896.9 898.6 899.8 900.5 653.2 656.4 666.2 667.2 668.7 14820.2 14882.3 14904.2 14943.6 15137.3 10315.4 13918.3 14219.9 14344.2 14595.5 11571.7 11802.8 11919.3 11994.9 12218.0 3350.9 3710.7 3727.7 3888.8 3902.5 96403.0 116433.0 118434.0 121580.6 123040.2 9213.5 10835.1 10890.6 10953.3 11140.9 18652.4 22112.4 22191.7 22263.8 22282.7 5926.0 6192.3 7227.7 7366.9 7513.5 3492.9 3524.3 4044.7 4167.1 4288.8 2974.4 3365.7 3438.2 3464.6 3498.9 580.0 624.3 627.2 638.2 642.4 520.7 605.3 649.6 662.4 675.4 7254.4 8852.5 8873.7 9000.7 9235.1 529.8 573.3 606.1 637.8 678.4 3991.4 4029.8 4322.4 4378.3 4569.4 99662.0 99735.5 100630.5 103306.0 103345.5 73803.9 75772.0 76884.6 83041.2 88293.0 77006.0 78195.0 79279.0 79781.0 80067.0 7325.6 9570.0 9652.3 9658.4 9706.2 42150.6 47513.8 47562.0 50400.5 51301.2 59257.0 66796.0 71802.9 73564.6 75011.0 12664.9 12760.3 12796.1 12909.4 13035.0 10722.8 13031.5 13498.5 13646.5 14018.6 12946.7 13325.8 13473.7 13586.9 13637.5 12671.8 12745.4 12805.4 12912.7 13571.5 13232.4 13299.7 13326.9 13355.1 13697.6 9870.9 10703.6 10725.9 10828.5 11480.5 10632.9 10774.4 11301.8 11320.3 11339.0 05:20:10 INFO - PROCESS | 1648 | -------- Summary: end -------- 05:20:10 INFO - PROCESS | 1648 | 05:20:10 INFO - TEST-INFO | 1648: exit 0 05:20:10 INFO - TEST-OK | dromaeo_css | took 1118218ms 05:20:10 INFO - TEST-START | kraken 05:20:10 INFO - operating with platform_type : mac_ 05:20:10 INFO - Initialising browser for kraken test... 05:20:10 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpZSmjzb/profile http://localhost:49244/getInfo.html) 05:20:14 INFO - PROCESS | 1659 | 2016-11-29 05:20:14.065 plugin-container[1660:10394] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:20:14 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:14 INFO - PROCESS | 1659 | 2016-11-29 05:20:14.068 plugin-container[1660:10394] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.CFPasteboardClient' 05:20:14 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:14 INFO - PROCESS | 1659 | 2016-11-29 05:20:14.068 plugin-container[1660:10394] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:20:14 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:20:14 INFO - PROCESS | 1659 | colorDepth:24 05:20:14 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:20:14 INFO - PROCESS | 1659 | __metrics 05:20:14 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 05:20:15 INFO - TEST-INFO | 1659: exit 0 05:20:15 INFO - Browser initialized. 05:20:15 INFO - Running cycle 1/1 for kraken test... 05:20:15 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpZSmjzb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:17 INFO - PROCESS | 1661 | 2016-11-29 05:20:17.823 plugin-container[1662:10511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:20:17 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:17 INFO - PROCESS | 1661 | 2016-11-29 05:20:17.824 plugin-container[1662:10511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 05:20:17 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:17 INFO - PROCESS | 1661 | 2016-11-29 05:20:17.824 plugin-container[1662:10511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:20:27 INFO - PROCESS | 1661 | RSS: Main: 242749440 05:20:27 INFO - PROCESS | 1661 | 05:20:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:31 INFO - PROCESS | 1661 | RSS: Main: 254660608 05:20:31 INFO - PROCESS | 1661 | 05:20:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:20:34 INFO - PROCESS | 1661 | RSS: Main: 262389760 05:20:34 INFO - PROCESS | 1661 | 05:20:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:20:38 INFO - PROCESS | 1661 | RSS: Main: 265003008 05:20:38 INFO - PROCESS | 1661 | 05:20:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:20:41 INFO - PROCESS | 1661 | RSS: Main: 266248192 05:20:41 INFO - PROCESS | 1661 | 05:20:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:43 INFO - PROCESS | 1661 | RSS: Main: 266510336 05:20:43 INFO - PROCESS | 1661 | 05:20:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:47 INFO - PROCESS | 1661 | RSS: Main: 273559552 05:20:47 INFO - PROCESS | 1661 | 05:20:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:51 INFO - PROCESS | 1661 | RSS: Main: 272744448 05:20:51 INFO - PROCESS | 1661 | 05:20:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:20:54 INFO - PROCESS | 1661 | RSS: Main: 273264640 05:20:54 INFO - PROCESS | 1661 | 05:20:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:57 INFO - PROCESS | 1661 | RSS: Main: 272277504 05:20:57 INFO - PROCESS | 1661 | 05:20:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:59 INFO - PROCESS | 1661 | RSS: Main: 272359424 05:20:59 INFO - PROCESS | 1661 | 05:21:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:21:02 INFO - PROCESS | 1661 | RSS: Main: 272384000 05:21:02 INFO - PROCESS | 1661 | 05:21:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:21:06 INFO - PROCESS | 1661 | RSS: Main: 272625664 05:21:06 INFO - PROCESS | 1661 | 05:21:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:21:10 INFO - PROCESS | 1661 | RSS: Main: 272465920 05:21:10 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:21:12 INFO - PROCESS | 1661 | RSS: Main: 274235392 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | __start_tp_report 05:21:12 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:21:12 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:21:12 INFO - PROCESS | 1661 | |i|pagename|runs| 05:21:12 INFO - PROCESS | 1661 | |0;ai-astar;105;120;122;120;118;120;118;119;118;141 05:21:12 INFO - PROCESS | 1661 | |1;audio-beat-detection;148;118;115;167;142;116;117;123;170;151 05:21:12 INFO - PROCESS | 1661 | |2;audio-dft;211;209;193;195;198;196;195;202;199;193 05:21:12 INFO - PROCESS | 1661 | |3;audio-fft;79;59;59;87;60;62;82;51;87;72 05:21:12 INFO - PROCESS | 1661 | |4;audio-oscillator;95;71;70;74;89;105;116;92;72;82 05:21:12 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;89;90;88;92;107;119;132;81;133 05:21:12 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;96;96;96;105;96;96;95;96;100 05:21:12 INFO - PROCESS | 1661 | |7;imaging-desaturate;93;141;149;93;92;92;93;93;92;93 05:21:12 INFO - PROCESS | 1661 | |8;json-parse-financial;58;56;56;62;81;91;89;89;89;62 05:21:12 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;97;114;75;74;68;68;70;75;70 05:21:12 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;86;91;89;93;89;90;88;92;89;85 05:21:12 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;149;165;131;162;162;162;176;167;195;165 05:21:12 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;175;177;185;156;215;217;190;159;231;243 05:21:12 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;70;68;70;71;81;86;92;91;93;92 05:21:12 INFO - PROCESS | 1661 | __end_tp_report 05:21:12 INFO - PROCESS | 1661 | __start_cc_report 05:21:12 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,963 05:21:12 INFO - PROCESS | 1661 | __end_cc_report 05:21:12 INFO - PROCESS | 1661 | __startTimestamp1480425672319__endTimestamp 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:21:12 INFO - PROCESS | 1661 | Number of tests: 14 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:120.10 Median:120.00 stddev:8.71 (7.3%) stddev-sans-first:7.33 05:21:12 INFO - PROCESS | 1661 | Values: 105.0 120.0 122.0 120.0 118.0 120.0 118.0 119.0 118.0 141.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:145.00 stddev:21.63 (14.9%) stddev-sans-first:22.56 05:21:12 INFO - PROCESS | 1661 | Values: 148.0 118.0 115.0 167.0 142.0 116.0 117.0 123.0 170.0 151.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:199.10 Median:198.50 stddev:6.38 (3.2%) stddev-sans-first:5.12 05:21:12 INFO - PROCESS | 1661 | Values: 211.0 209.0 193.0 195.0 198.0 196.0 195.0 202.0 199.0 193.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:69.80 Median:75.50 stddev:13.22 (17.5%) stddev-sans-first:13.60 05:21:12 INFO - PROCESS | 1661 | Values: 79.0 59.0 59.0 87.0 60.0 62.0 82.0 51.0 87.0 72.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:78.00 stddev:15.71 (20.1%) stddev-sans-first:16.36 05:21:12 INFO - PROCESS | 1661 | Values: 95.0 71.0 70.0 74.0 89.0 105.0 116.0 92.0 72.0 82.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:102.10 Median:88.50 stddev:19.35 (21.9%) stddev-sans-first:20.02 05:21:12 INFO - PROCESS | 1661 | Values: 90.0 89.0 90.0 88.0 92.0 107.0 119.0 132.0 81.0 133.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:3.05 (3.2%) stddev-sans-first:3.20 05:21:12 INFO - PROCESS | 1661 | Values: 96.0 96.0 96.0 96.0 105.0 96.0 96.0 95.0 96.0 100.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:93.00 stddev:22.17 (23.8%) stddev-sans-first:23.21 05:21:12 INFO - PROCESS | 1661 | Values: 93.0 141.0 149.0 93.0 92.0 92.0 93.0 93.0 92.0 93.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:73.30 Median:85.00 stddev:15.64 (18.4%) stddev-sans-first:15.57 05:21:12 INFO - PROCESS | 1661 | Values: 58.0 56.0 56.0 62.0 81.0 91.0 89.0 89.0 89.0 62.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:77.80 Median:72.00 stddev:15.42 (21.4%) stddev-sans-first:15.85 05:21:12 INFO - PROCESS | 1661 | Values: 67.0 97.0 114.0 75.0 74.0 68.0 68.0 70.0 75.0 70.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.50 stddev:2.49 (2.8%) stddev-sans-first:2.35 05:21:12 INFO - PROCESS | 1661 | Values: 86.0 91.0 89.0 93.0 89.0 90.0 88.0 92.0 89.0 85.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:163.40 Median:165.00 stddev:16.46 (10.0%) stddev-sans-first:16.61 05:21:12 INFO - PROCESS | 1661 | Values: 149.0 165.0 131.0 162.0 162.0 162.0 176.0 167.0 195.0 165.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.80 Median:202.50 stddev:30.09 (14.9%) stddev-sans-first:31.05 05:21:12 INFO - PROCESS | 1661 | Values: 175.0 177.0 185.0 156.0 215.0 217.0 190.0 159.0 231.0 243.0 05:21:12 INFO - PROCESS | 1661 | 05:21:12 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.40 Median:88.50 stddev:10.65 (12.0%) stddev-sans-first:10.46 05:21:12 INFO - PROCESS | 1661 | Values: 70.0 68.0 70.0 71.0 81.0 86.0 92.0 91.0 93.0 92.0 05:21:12 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:21:12 INFO - PROCESS | 1661 | 05:21:13 INFO - TEST-INFO | 1661: exit 0 05:21:13 INFO - TEST-OK | kraken | took 62686ms 05:21:13 INFO - SUITE-END | took 1180s 05:21:13 INFO - Completed test suite (00:19:41) 05:21:13 INFO - javascript benchmark 05:21:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7078.975331867456, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3001.579778830964, 3400.2859399526096, 3415.9316813663727, 3449.9655003449957, 3454.980780669703, 4206.684498662605, 4602.58576728094, 5588.273524441827, 5696.829095127143, 5731.627444216123, 5352.518273355407, 5606.55147588193, 5701.828945131652, 5706.1440783882435, 5786.797462088828, 4604.654650901179, 5077.771500282496, 5198.116320225552, 5209.1404918188455, 5269.394019687747, 5238.188080847455, 5268.946210757845, 5281.287026251464, 5285.180796976456, 5325.227841962933, 5111.591072714174, 5247.16045432729, 5260.815871444519, 5274.7626356814035, 5291.920621190685, 5128.692278463304, 5161.045206636784, 5187.118189907725, 5192.766325515361, 5209.6874187548865, 5120.718360490171, 5122.769475373617, 5124.871878203037, 5151.665141765773, 5168.173092305213, 5031.496850314976, 5327.9733601331745, 5601.159826026088, 5670.68811215383, 5677.716114194273, 6230.719617617218, 6231.096491008779, 6236.158118654014, 6236.532260080512, 6238.781642642532, 6234.470070044052, 6235.532335074888, 6261.0, 6270.278917924452, 6302.55882088254, 5683.54531637468, 5684.971575142181, 5777.277840269966, 5905.940940590625, 5921.052631578969, 6201.782937597162, 6207.441330280206, 6244.1570387997035, 6245.0, 6252.437280644673, 6161.075838624152, 6166.982447896047, 6172.753089876444, 6186.81439556814, 6194.34959329273, 6223.408776166257, 6249.96875015622, 6252.781152659635, 6255.937440625627, 6256.937430625727, 6099.329073801879, 6125.84685382869, 6127.769884593299, 6132.080187971751, 6148.047052706837, 6140.197513349931, 6142.314611233224, 6161.4146656067605, 6205.47253483459, 6270.090836828635, 4866.78099485524, 4895.241237608106, 4910.361652985089, 4946.0, 4962.280469331927, 4243.2998555238655, 4425.291953287523, 4460.375547423363, 4468.642508599304, 4469.620082292977, 5549.112142057252, 5720.170575266563, 5807.506361959281, 5849.824505264849, 5881.382454842265, 6233.065040243909, 6244.406781355764, 6258.3428739982555, 6262.3111457739615, 6292.05619157121, 5808.970955145197, 5884.7940322088525, 5896.115582662549, 5902.314750313473, 5928.970355148197, 5277.287566178594, 5342.54588359986, 5371.221172929982, 5423.972880135574, 5433.157860531544, 5701.315842098808, 5855.765769369177, 5999.1601175836295, 6233.719482623384, 6259.937400626027, 5210.947890521046, 6246.1255424239735, 6269.27286630299, 6282.937170628418, 6295.622262664072, 5223.45153758865, 6276.6547839869245, 6293.527985401205, 6346.777862774966, 6355.3326900674765, 5248.580113590827, 6072.574919755575, 6127.84680382994, 6135.447809697149, 6254.687265636791, 6105.732692890381, 6198.132261483486, 6200.255969283715, 6201.720922558406, 6269.279032911091, 6094.8171554853425, 6108.114323423168, 6151.046587778901, 6160.291566469913, 6170.7223174958135, 5123.974380128076, 5361.812336568201, 6012.789552365646, 6099.542534309856, 6170.475509581556, 5697.686627235542, 5974.163617093697, 5983.401659833982, 5996.820095397145, 6107.633541987318, 6032.909506357496, 6037.516998640186, 6039.78860739872, 6127.264688495017, 6162.907556386568, 5828.184054232326, 5904.350521442552, 5926.733297001559, 5927.377625349276, 5932.139839723302], "value": 5641.510021907942, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.4917035453483, 519.1926554208206, 520.6901893373441, 529.7563120964356, 546.0307953382745, 36200.45699314509, 38564.61435385652, 38689.61310386896, 38700.41949370758, 38722.41916371257, 38913.22173556527, 39539.20921581567, 39584.40623390644, 39587.802060989656, 39688.80155599225, 8463.873041904377, 8669.869951950726, 9149.725508234764, 9425.528723563828, 11101.44492775362, 11100.278481898651, 11334.433278336091, 11341.149413793957, 11351.886481135165, 11379.089672826154, 11338.60314888979, 11362.37506937118, 11374.71563210918, 11376.033037191808, 11393.0, 11290.491927863224, 11325.943370283096, 11351.716207094847, 11370.431478426046, 11386.089112871034, 9057.139571740674, 9677.935427103013, 10288.176945844314, 11235.269707468988, 11275.293929456953, 31182.22044448884, 38532.42201366982, 38708.41937370942, 38880.22239555221, 38882.61117388818, 3330.417176994041, 3516.2791627716256, 3586.910327241814, 3749.193923306475, 3972.344563147077, 29156.562651560245, 29208.0, 29582.964596239242, 29794.55308170379, 30071.646775895137, 2829.3775369418695, 2844.5022121128927, 2845.6585209774958, 2856.6714827794654, 2968.0650595062702, 306675.0, 376913.0, 378062.0, 382850.0, 385428.0728596367, 53810.461895381326, 54291.0, 54484.45515544794, 54567.0, 54680.0, 57311.71344143336, 57992.42007579954, 58013.12980305299, 58119.70940145357, 58354.416455835744, 14556.0, 14792.630184245265, 14952.925235373754, 14971.251437428085, 14991.400343986119, 9854.162396196403, 10163.390196588229, 10639.893601064045, 11682.824757628643, 11707.297562146128, 15367.0, 15401.845981540266, 15412.383504659912, 15415.229238538028, 15511.069335839886, 6822.488313376517, 6911.101556797685, 6919.342662447059, 6932.618705971119, 6944.548604340702, 838.6623335779421, 918.5682729117304, 1000.5935724582391, 1005.3113617172261, 1007.3351587951917, 24487.387815304402, 28591.42817143687, 28698.856505717336, 29051.12846614605, 29548.261293467836, 979.7354714227118, 1090.4438736244572, 1104.8895110488886, 1105.5135740274254, 1108.257467496779, 12585.496580136693, 14654.413823446943, 14829.184394858386, 14963.027403218755, 15010.699786004438, 489809.0, 492931.0, 494814.0, 497438.0, 500418.0, 52688.473115269124, 53051.0, 53196.202056969174, 53569.1964620531, 53738.731306343216, 1328.3690247132536, 1364.1405914273944, 1373.2378529915993, 1377.1117629129155, 1377.7313423882447, 28491.002814902207, 28523.144305670863, 28571.857140714164, 29284.560731588615, 29513.409731805674, 45993.54006460027, 46332.07335853197, 46409.071818564116, 46514.0, 46541.30188047114, 417061.0, 422020.0, 423622.0, 425403.0, 425607.231783047, 24507.50984980326, 25224.747752522973, 25389.61915571231, 26099.608505872806, 36999.44500832436, 44160.33759493546, 44306.0, 45134.32298515591, 45431.31853022141, 45620.54379456296, 31385.0, 34964.0, 36781.63218367782, 37007.259854802214, 37063.0, 36145.27709445744, 36184.45723314205, 36909.446358305184, 37083.6291637091, 37240.441393378576, 35927.82036089907, 36053.0, 36695.0, 36783.81608091942, 37365.252694945404, 26091.08681196149, 31444.899428519893, 32334.35331293408, 32631.510527341634, 32816.50775238421, 19751.40745777629, 23157.842107894874, 23480.412989675395, 24843.62734558947, 24966.126185584144], "value": 18906.14480442734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.32938171077313, 338.1478673742169, 371.8902923637527, 384.1989451992595, 400.81362166592527, 18673.439796806084, 19023.809761902376, 19129.808701912993, 19776.90111549444, 19936.50158746032, 15692.529224123295, 18069.7289540657, 18379.448616541526, 18905.90547047263, 18955.905220473913, 5627.2684551008315, 5904.852378690545, 5920.496757775572, 5921.851953701149, 5935.940640593603, 5580.972095139518, 5823.272090988627, 5857.589968702193, 5858.41415858415, 5862.472377486026, 5333.63992181993, 5769.221155144065, 5789.623674461168, 5794.130880367937, 5826.446487583673, 4260.190563792885, 4884.755762211875, 5539.944600554003, 5795.913061304083, 5810.912836307459, 4264.424302719124, 4990.925136122961, 5044.6973181608855, 5063.645544811865, 5079.263506791494, 23488.647670284958, 23731.288061358187, 23848.523029539487, 24156.275311740676, 24180.39549011271, 2175.9020844062056, 2291.014863608648, 2414.985706003483, 2431.088341871798, 2716.212298433452, 14528.927355363261, 14659.193744344057, 14859.554213373616, 14993.32530036151, 15160.31778569967, 1794.371969810569, 1795.721663142215, 1796.4610616815035, 1823.5714607067462, 1899.4586542835225, 95178.52410737971, 102174.99999999926, 103194.0, 103639.0, 104787.4760626192, 8655.913440865637, 9824.901750982399, 10029.348092373972, 10105.545250463747, 10289.3311934724, 12639.115261931576, 12645.74708505843, 12671.0, 12671.0, 12680.429380677891, 7756.263155000379, 7883.84232315362, 7986.161453047463, 8083.070446898682, 8096.878546821802, 7862.208915834911, 7987.84024319522, 8003.959980200062, 8085.919140808634, 8308.5430301334, 5215.687058776485, 6348.8412789679705, 6356.2054743157105, 6372.968135159387, 6383.521235907326, 1548.5741421109285, 1754.166770783893, 1754.2895127473394, 1786.2944137065786, 1801.6126532795513, 725.9020032295573, 871.7602659268625, 974.5176137811687, 1000.9599616015278, 1028.93311934724, 20374.490637734176, 20819.271325503836, 20911.47721306949, 20993.47516312074, 21318.41502999121, 896.9955150224207, 902.1474706402518, 921.1249313152532, 940.0, 953.7997020625746, 10458.111060559786, 10694.625688101034, 11281.0, 11284.21010529255, 11287.492062857027, 415841.0, 454888.0, 503003.4849825727, 511155.8884411183, 516611.0, 69981.65009175023, 82248.58875705703, 82585.76121358185, 101331.49334253282, 106049.99999999846, 962.1148543340144, 981.617169303972, 990.2474119669104, 993.2649839119, 996.5798736800031, 12676.492940282285, 18120.18459169367, 18589.977551234813, 18641.067946602885, 18880.43358699241, 34074.65925340814, 44920.0, 49652.0, 50438.74780626073, 52469.95060098853, 76900.2309976893, 77247.0, 77689.61155194188, 77948.0, 80548.0, 7753.883691744742, 7868.960655196687, 7987.9600601998945, 8070.798230044295, 8212.219839115274, 10270.794584108426, 11156.768646270615, 12371.000775486582, 12515.0, 12784.61646150617, 8965.641374344954, 9506.952465237906, 10406.323588966692, 10470.0, 10976.835347469634, 10449.58201671955, 10647.361158330215, 10716.0, 10959.56161753521, 10966.835497467704, 9579.760505987406, 10590.735231618963, 11148.996590306911, 11195.2723073, 11309.151813614177, 7883.172266912121, 8034.156413576724, 8083.3533317333295, 8227.670893164206, 8353.791155221168, 9783.315167938175, 10682.73293167677, 10961.671149865517, 10975.560977561128, 11029.889701103208], "value": 9151.280228447076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.308015232841, 1481.1705444950828, 1677.8825482216241, 1784.232779904642, 1811.8188181181874, 7484.064491938508, 7711.807204819868, 7965.84068318634, 8390.160983901607, 8441.4253652636, 7919.485233459821, 8213.096559378464, 8264.09094999551, 8347.248747612713, 8383.580820958943, 2192.0464597899927, 2218.1460137846966, 2230.966535501969, 2232.531168454618, 2232.810211132056, 2280.293109136173, 2311.514581937795, 2325.3140323604493, 2328.557574060923, 2336.9883150584224, 1920.7310976463307, 2269.375921621559, 2282.2468585366873, 2299.9655005174936, 2303.8387312888103, 1996.17158879065, 2190.386691726303, 2251.1783199132283, 2258.7854153855355, 2286.622707253301, 2271.216430331533, 2280.8289378296695, 2304.781045800646, 2306.6770652108717, 2307.065638416444, 8274.917250827475, 8299.875501867476, 8347.666093356258, 8392.370572207108, 8425.831483370359, 4956.851294461172, 5076.44159142494, 5107.4892510749, 5112.846614601568, 5115.335006449175, 2970.4653162430754, 3225.645179030305, 3528.347255757693, 3532.275883443888, 3536.752427330088, 939.262678797141, 1019.2763138171907, 1076.7146706122883, 1084.9728756781064, 1085.8154113800672, 10566.890476499933, 10588.205884558689, 10606.15150787935, 10699.679009629723, 10728.946355268252, 4441.689081764245, 4546.727196368229, 4612.492625811158, 4652.976735116303, 4675.041616468616, 9251.0, 9274.397164184307, 9317.953410232905, 9344.018878017847, 9352.111549402796, 5091.770870310845, 5095.420419669914, 5098.949010509921, 5108.9744551277745, 5110.131277682803, 4117.5882411758585, 4401.2517871961845, 4635.096156249499, 4666.533346665306, 4684.391029166187, 3717.8436506825974, 3726.869559565402, 3800.562935262425, 3827.7129215308964, 3839.176391214151, 1259.238160912653, 1260.5966090851023, 1264.3551788587702, 1272.6500212441654, 1274.3182397417522, 797.4298376660715, 822.3256929318021, 864.312871267344, 908.5321059654283, 940.6660635474308, 5570.972145139248, 5571.9442805572235, 5706.572007099401, 5825.621334613236, 5849.1226316052935, 968.1528662420383, 994.3536701144315, 1015.6496008876926, 1026.3995562595817, 1046.1787496814964, 3063.0, 3778.677462887967, 3826.1582451860545, 3868.903277418087, 3957.2876882161486, 9768.853467197998, 9986.20110391167, 10062.39625622465, 10067.942865999112, 10092.44491552957, 9920.305578609572, 9940.900590994142, 9959.352642078242, 10049.69850904474, 10065.748356291151, 7685.5772932488135, 7732.530819144345, 7871.370290376756, 7873.094594121635, 7895.802604934807, 4656.324832899279, 5523.8066667666435, 5655.604107712421, 5688.232088667977, 5718.685472299064, 9269.44383337002, 9298.442093474143, 9372.437653740797, 9433.57548910287, 9455.952720236355, 7177.282271772781, 9315.86026209594, 9326.393784403992, 9416.199623031845, 9425.434473931586, 6576.80269591913, 6772.55978361405, 6926.688299026455, 7073.151221853204, 7227.2772722729405, 7695.4997925134685, 7923.247291507298, 7944.285014348737, 7960.721374751856, 7973.322267607318, 5483.4516548344845, 6470.547061705635, 7044.0, 7142.071530701183, 7187.209406965125, 6890.552114112566, 6934.722611095702, 6943.756968506078, 6949.791506254821, 6989.33601307875, 6495.408624886933, 6552.148220731465, 6611.7271001897525, 6619.238787539494, 6650.501212408992, 4749.145153872336, 4807.663463557517, 4838.225883858566, 4850.0, 4918.237673160523, 4823.614110871192, 4920.975395123001, 4922.1632322505975, 4930.2604609307455, 4947.406311242674], "value": 4375.700289941057, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.55093431582526, 293.2214969256624, 309.97520198384126, 312.4750419295584, 314.0750489807518, 5239.580833533318, 5440.537554307879, 5458.672479651219, 5537.667739935607, 5539.446055394458, 4406.69153159279, 5041.77312020959, 5261.842144735654, 5323.840284791453, 5375.650582712121, 3629.60074391818, 4168.145530166324, 4174.895627609304, 4178.164367126572, 4189.539150693421, 3840.36633955397, 4044.024563998944, 4094.263032654121, 4167.979160104211, 4194.727342722713, 3571.839267232968, 4070.9185816283657, 4129.876103716878, 4144.647704945083, 4148.755373387987, 3651.981740091309, 4084.6323830855367, 4108.979455102721, 4126.690498212631, 4133.834646614132, 3586.7130629549833, 3605.0987253186704, 3633.4913112164318, 3635.4365073413665, 3665.7433979621437, 5101.974490127563, 5227.60792940531, 5290.656107353048, 5300.072487314705, 5310.548603368679, 4189.973456503176, 4734.715917044989, 4750.667453278272, 4765.022515655998, 4995.700257984533, 4204.456303936136, 4593.632509399274, 5443.591730620219, 5590.608657393984, 5730.512906402961, 3277.852496637657, 3352.413327667673, 3563.4832949222478, 3813.103920578662, 3957.208558288325, 5155.40712818027, 5902.491441137542, 5945.710901302986, 5957.523398128139, 5993.640381577119, 3392.338493993697, 3401.9489707654407, 4021.6380525752825, 4038.1519880824767, 4042.7574345538774, 3863.362545180014, 4123.463949686581, 4124.546299907008, 4129.215449064668, 4157.584241575879, 3737.8131093445218, 4410.757408342508, 4675.29870519418, 4707.458642256116, 4707.835225767081, 5091.439941606421, 5739.196512488255, 5740.913886291709, 5742.770289188469, 5773.740181691834, 1170.169179882276, 1271.961841144767, 1287.2557684337776, 1290.9027326772357, 1349.743548725739, 908.2416182487565, 913.5021413329752, 914.6752902719438, 914.9130832570895, 921.3136213520836, 4729.597984171315, 4909.25721368916, 5156.329677141947, 5228.581713462914, 5235.816746413857, 4285.571442855689, 4743.83396581118, 5439.322182922762, 5546.55627549795, 5580.91628625571, 4157.0, 4351.630111440563, 4496.258117410591, 4523.457185137804, 4560.143447228757, 2454.742252063543, 2564.3076369380165, 2686.556718141524, 2777.2084955787504, 2872.7503535961923, 5046.369203849519, 5249.107651699221, 5335.33308336458, 5356.848442457049, 6128.540359473057, 5041.697498150123, 6046.035038612468, 6099.969500152471, 6110.786122485781, 6112.572119951649, 5392.0, 5507.862303442366, 6173.7401193176565, 6179.969100154651, 6219.253689557282, 4906.607471402351, 5614.108734002074, 5871.6770577618645, 5944.256967879015, 5974.342822289632, 4605.585497305258, 4926.965337279212, 4995.15082435994, 5870.765169393348, 5971.596190171561, 5143.897122057613, 5379.305518761577, 5907.4683278505145, 6083.270007599116, 6152.323244443197, 3903.726739128234, 3931.6854651627227, 3943.881683549498, 3946.8421263149153, 3947.5591409134886, 4969.266903203296, 5966.850828729316, 5981.820545383645, 6005.069214271794, 6022.435545255692, 5399.408284023612, 5740.454656807597, 5749.367569567428, 5797.8550536236935, 5800.796972105956, 4779.900622856832, 4954.900901982012, 5721.856953576027, 5731.054376027909, 5772.3650398456975, 4786.904261914672, 4872.707637541759, 5241.292425522505, 5424.349078110652, 5631.436856314336, 4563.960894608362, 4770.570648641639, 4947.208446648519, 5674.801381951612, 5724.885502290014, 4858.562729354375, 5361.758720857649, 5616.5787565931905, 5621.971890140523, 5653.434656534314], "value": 4079.010885027816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.4408286831889, 660.3594513322076, 718.2566044144312, 751.4589495563195, 819.7991492084439, 14275.214863182533, 15097.471588494405, 15198.772018419733, 15218.391264349413, 15250.0, 13256.138351007177, 13716.0398772086, 13835.03154779166, 13854.238016909067, 13855.0, 822.0710597025353, 829.6308142876422, 837.8282452097305, 839.9244068033876, 839.9706010289641, 668.7793028300673, 688.1398252184769, 805.7179987004531, 847.7075408983891, 849.0660273698943, 872.4808738800403, 875.6497401039571, 903.5933829776594, 904.2991681446865, 906.4153620914507, 896.5203616065406, 896.8878890138733, 898.6315610599688, 899.7615631857531, 900.5362238447204, 653.1769969838039, 656.4190691238289, 666.2271764752863, 667.2326824152216, 668.6589839182003, 14820.184889831055, 14882.255887205596, 14904.18027008514, 14943.59164046609, 15137.318820653098, 10315.43265120418, 13918.304084795822, 14219.928900355535, 14344.211068391234, 14595.489157879423, 11571.652850414417, 11802.763944721144, 11919.284842909366, 11994.88005119955, 12217.961473274694, 3350.949735753966, 3710.684591809699, 3727.683146932514, 3888.8055597220027, 3902.5316961964745, 96402.9999999993, 116433.0, 118434.0, 121580.56838863173, 123040.1543976841, 9213.539323033821, 10835.079018283534, 10890.564377424815, 10953.34279943206, 11140.944295278472, 18652.44042678722, 22112.44718882041, 22191.66712499314, 22263.777362226494, 22282.665760013613, 5926.0, 6192.287886893065, 7227.747028853965, 7366.8526629467115, 7513.47405681608, 3492.860285588599, 3524.2599054198904, 4044.7168698190844, 4167.083241686824, 4288.785560721952, 2974.4348573770912, 3365.74756893234, 3438.1810655668132, 3464.566929133858, 3498.9300213996084, 579.9797007104731, 624.3350831364583, 627.190923708411, 638.1990601794718, 642.4250295985067, 520.6987697994732, 605.3250625552478, 649.6297110646981, 662.4303099334569, 675.4090171100287, 7254.383377412979, 8852.513111778786, 8873.73378798637, 9000.68497602594, 9235.07649235071, 529.7907326605969, 573.3034363248673, 606.1271768653125, 637.8118455055769, 678.4063944048958, 3991.401289806552, 4029.7963119319625, 4322.3516472528745, 4378.299472084451, 4569.405977222939, 99662.0, 99735.50132249437, 100630.4968475153, 103305.96694033114, 103345.48327258466, 73803.89294160593, 75772.0, 76884.61557692176, 83041.16958830455, 88293.0, 77006.0, 78195.0, 79279.0, 79781.0, 80067.0, 7325.597092159983, 9569.952150239204, 9652.27607929394, 9658.3722058066, 9706.223502119954, 42150.578494214664, 47513.762431189, 47562.0, 50400.49599504105, 51301.23048154206, 59257.0, 66796.0, 71802.92295615465, 73564.63217683877, 75011.0, 12664.936675316565, 12760.32033272986, 12796.10427270082, 12909.419076141408, 13035.0, 10722.839157412489, 13031.464390824025, 13498.52755153565, 13646.454141834216, 14018.579442616738, 12946.741065178832, 13325.800112998506, 13473.730525389634, 13586.86413135856, 13637.522686705917, 12671.80992285135, 12745.362731863555, 12805.359732013176, 12912.677183070498, 13571.52499662507, 13232.40454179545, 13299.66750831237, 13326.86673133295, 13355.131916425402, 13697.589072327846, 9870.851937220803, 10703.571857125628, 10725.927407259524, 10828.458577071273, 11480.540778368771, 10632.936606742815, 10774.353538787698, 11301.773964520498, 11320.320780753182, 11339.0], "value": 7222.6174600561335, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 120.0, 122.0, 120.0, 118.0, 120.0, 118.0, 119.0, 118.0, 141.0], "value": 120.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 115.0, 167.0, 142.0, 116.0, 117.0, 123.0, 170.0, 151.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 209.0, 193.0, 195.0, 198.0, 196.0, 195.0, 202.0, 199.0, 193.0], "value": 199.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 87.0, 60.0, 62.0, 82.0, 51.0, 87.0, 72.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 71.0, 70.0, 74.0, 89.0, 105.0, 116.0, 92.0, 72.0, 82.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 88.0, 92.0, 107.0, 119.0, 132.0, 81.0, 133.0], "value": 102.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 105.0, 96.0, 96.0, 95.0, 96.0, 100.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 141.0, 149.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 62.0, 81.0, 91.0, 89.0, 89.0, 89.0, 62.0], "value": 73.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 97.0, 114.0, 75.0, 74.0, 68.0, 68.0, 70.0, 75.0, 70.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 89.0, 93.0, 89.0, 90.0, 88.0, 92.0, 89.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 165.0, 131.0, 162.0, 162.0, 162.0, 176.0, 167.0, 195.0, 165.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 185.0, 156.0, 215.0, 217.0, 190.0, 159.0, 231.0, 243.0], "value": 194.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 71.0, 81.0, 86.0, 92.0, 91.0, 93.0, 92.0], "value": 81.4, "unit": "score"}]}]} 05:21:14 INFO - Return code: 0 05:21:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:21:14 ERROR - # TBPL SUCCESS # 05:21:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:14 INFO - 2016-11-29 05:21:14.127 screenresolution[1666:10898] starting screenresolution argv=screenresolution get 05:21:14 INFO - 2016-11-29 05:21:14.130 screenresolution[1666:10898] Display 0: 1600x1200x32@60 05:21:14 INFO - 2016-11-29 05:21:14.136 screenresolution[1667:10901] starting screenresolution argv=screenresolution list 05:21:14 INFO - Available Modes on Display 0 05:21:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:14 INFO - Intel Iris: 05:21:14 INFO - Chipset Model: Intel Iris 05:21:14 INFO - Type: GPU 05:21:14 INFO - Bus: Built-In 05:21:14 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:14 INFO - Vendor: Intel (0x8086) 05:21:14 INFO - Device ID: 0x0a2e 05:21:14 INFO - Revision ID: 0x0009 05:21:14 INFO - Displays: 05:21:14 INFO - Display: 05:21:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:14 INFO - Display Serial Number: 1600x1200 60 05:21:14 INFO - Main Display: Yes 05:21:14 INFO - Mirror: Off 05:21:14 INFO - Online: Yes 05:21:14 INFO - Rotation: Supported 05:21:14 INFO - Return code: 0 05:21:14 INFO - Running post-action listener: _resource_record_post_action 05:21:14 INFO - [mozharness: 2016-11-29 13:21:14.370265Z] Finished run-tests step (success) 05:21:14 INFO - Running post-run listener: _resource_record_post_run 05:21:14 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37812736; Write bytes: 696491008; Read time: 243; Write time: 2016 05:21:14 INFO - TinderboxPrint: CPU usage
54.5% 05:21:14 INFO - TinderboxPrint: I/O read bytes / time
37,812,736 / 243 05:21:14 INFO - TinderboxPrint: I/O write bytes / time
696,491,008 / 2,016 05:21:14 INFO - TinderboxPrint: CPU idle
2,211.0 (45.5%) 05:21:14 INFO - TinderboxPrint: CPU system
985.0 (20.3%) 05:21:14 INFO - TinderboxPrint: CPU user
1,661.8 (34.2%) 05:21:14 INFO - TinderboxPrint: Swap in / out
421,457,920 / 0 05:21:14 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 362945024; Write bytes: 356767744; Read time: 27487; Write time: 738 05:21:14 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36846080; Write bytes: 310531072; Read time: 230; Write time: 1206 05:21:14 INFO - Running post-run listener: _upload_blobber_files 05:21:14 INFO - Blob upload gear active. 05:21:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:21:14 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 05:21:14 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'] 05:21:14 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 05:21:15 INFO - (blobuploader) - INFO - Open directory for files ... 05:21:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:21:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:21:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:16 INFO - (blobuploader) - INFO - Done attempting. 05:21:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:21:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:17 INFO - (blobuploader) - INFO - Done attempting. 05:21:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:21:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:21:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:20 INFO - (blobuploader) - INFO - Done attempting. 05:21:20 INFO - (blobuploader) - INFO - Iteration through files over. 05:21:20 INFO - Return code: 0 05:21:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:21:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:21:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6f030f7136c62316caca90251f52358c13db9f60a8299046a8cf4524d13c4e03dcc021c99a4f5dad642568fbb45b3470b1815a661e2a68d3e97b9296b5d6c49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07cab17acf479adebdbc309c9cdd93c4881972991f39ff6516d72668c44159301c2f7d29e9b13ff5fa8b52abad74606774c8a3d55c7252a787ad5f9188ea5762", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf1510e150fac5bdb605ab17e793234311b41cba2b4947f9e41ed41e99bc3d064dc061f224a91437a59d874d25fbe1027c58c3b9749d04154942053b655a633"} 05:21:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:21:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:21:20 INFO - Contents: 05:21:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6f030f7136c62316caca90251f52358c13db9f60a8299046a8cf4524d13c4e03dcc021c99a4f5dad642568fbb45b3470b1815a661e2a68d3e97b9296b5d6c49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07cab17acf479adebdbc309c9cdd93c4881972991f39ff6516d72668c44159301c2f7d29e9b13ff5fa8b52abad74606774c8a3d55c7252a787ad5f9188ea5762", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf1510e150fac5bdb605ab17e793234311b41cba2b4947f9e41ed41e99bc3d064dc061f224a91437a59d874d25fbe1027c58c3b9749d04154942053b655a633"} 05:21:20 INFO - Running post-run listener: copy_logs_to_upload_dir 05:21:20 INFO - Copying logs to upload dir... 05:21:20 INFO - mkdir: /builds/slave/test/build/upload/logs 05:21:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.039119 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-11-29 05:21:20.477605) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 05:21:20.485458) ========= 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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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/a6f030f7136c62316caca90251f52358c13db9f60a8299046a8cf4524d13c4e03dcc021c99a4f5dad642568fbb45b3470b1815a661e2a68d3e97b9296b5d6c49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07cab17acf479adebdbc309c9cdd93c4881972991f39ff6516d72668c44159301c2f7d29e9b13ff5fa8b52abad74606774c8a3d55c7252a787ad5f9188ea5762", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf1510e150fac5bdb605ab17e793234311b41cba2b4947f9e41ed41e99bc3d064dc061f224a91437a59d874d25fbe1027c58c3b9749d04154942053b655a633"} build_url:https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010965 build_url: 'https://queue.taskcluster.net/v1/task/O28Xe1NPSlGkYIrROpImag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6f030f7136c62316caca90251f52358c13db9f60a8299046a8cf4524d13c4e03dcc021c99a4f5dad642568fbb45b3470b1815a661e2a68d3e97b9296b5d6c49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07cab17acf479adebdbc309c9cdd93c4881972991f39ff6516d72668c44159301c2f7d29e9b13ff5fa8b52abad74606774c8a3d55c7252a787ad5f9188ea5762", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf1510e150fac5bdb605ab17e793234311b41cba2b4947f9e41ed41e99bc3d064dc061f224a91437a59d874d25fbe1027c58c3b9749d04154942053b655a633"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 05:21:20.533721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 05:21:20.534198) ========= 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.wLqBlBvIXL/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.FNKcdhgMWX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005001 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 05:21:20.586020) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 05:21:20.586532) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 05:21:20.586971) ========= ========= Total master_lag: 0.35 =========