builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0333 starttime: 1476307737.12 results: success (0) buildid: 20161012121646 builduid: 3d8cabcf75cf4b86a7d85127993de3e1 revision: 38171e4619fb49c80f0342b6c4a91970c5faccf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.121985) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.122429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.122698) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.149036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.149401) ========= 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.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-12 14:28:57-- 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% 17.9M=0.001s 2016-10-12 14:28:57 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089334 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.263180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.263510) ========= 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.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.030145 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:57.314397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 14:28:57.314750) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 38171e4619fb49c80f0342b6c4a91970c5faccf5 --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 38171e4619fb49c80f0342b6c4a91970c5faccf5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-12 14:28:57,402 truncating revision to first 12 chars 2016-10-12 14:28:57,402 Setting DEBUG logging. 2016-10-12 14:28:57,402 attempt 1/10 2016-10-12 14:28:57,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/38171e4619fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 14:28:58,106 unpacking tar archive at: mozilla-central-38171e4619fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978228 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 14:28:58.318742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:58.319067) ========= script_repo_revision: 38171e4619fb49c80f0342b6c4a91970c5faccf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:58.319432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:58.319690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 14:28:58.341937) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 14:28:58.342295) ========= /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.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:28:58 INFO - MultiFileLogger online at 20161012 14:28:58 in /builds/slave/test 14:28:58 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 14:28:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:28:58 INFO - {'append_to_log': False, 14:28:58 INFO - 'base_work_dir': '/builds/slave/test', 14:28:58 INFO - 'blob_upload_branch': 'Firefox', 14:28:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:28:58 INFO - 'branch': 'Firefox', 14:28:58 INFO - 'buildbot_json_path': 'buildprops.json', 14:28:58 INFO - 'config_files': ('talos/mac_config.py',), 14:28:58 INFO - 'default_actions': ('clobber', 14:28:58 INFO - 'read-buildbot-config', 14:28:58 INFO - 'download-and-extract', 14:28:58 INFO - 'populate-webroot', 14:28:58 INFO - 'create-virtualenv', 14:28:58 INFO - 'install', 14:28:58 INFO - 'run-tests'), 14:28:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:28:58 INFO - 'download_minidump_stackwalk': True, 14:28:58 INFO - 'download_symbols': 'ondemand', 14:28:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:28:58 INFO - 'tooltool.py': '/tools/tooltool.py', 14:28:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:28:58 INFO - '/tools/misc-python/virtualenv.py')}, 14:28:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:28:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:28:58 INFO - 'installer_path': 'installer.exe', 14:28:58 INFO - 'log_level': 'info', 14:28:58 INFO - 'log_name': 'talos', 14:28:58 INFO - 'log_to_console': True, 14:28:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:28:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:28:58 INFO - 'opt_config_files': (), 14:28:58 INFO - 'pip_index': False, 14:28:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:58 INFO - 'cmd': ('bash', 14:28:58 INFO - '-c', 14:28:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:58 INFO - 'enabled': True, 14:28:58 INFO - 'halt_on_failure': False, 14:28:58 INFO - 'name': 'check_screen_resolution'},), 14:28:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:58 INFO - 'cmd': ('bash', 14:28:58 INFO - '-c', 14:28:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:58 INFO - 'enabled': True, 14:28:58 INFO - 'halt_on_failure': False, 14:28:58 INFO - 'name': 'check_screen_resolution'},), 14:28:58 INFO - 'run_cmd_checks_enabled': True, 14:28:58 INFO - 'sps_profile': False, 14:28:58 INFO - 'sps_profile_interval': 0, 14:28:58 INFO - 'suite': 'dromaeojs-e10s', 14:28:58 INFO - 'system_bits': '32', 14:28:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:28:58 INFO - 'title': 't-yosemite-r7-0333', 14:28:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:28:58 INFO - 'use_talos_json': True, 14:28:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:28:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:28:58 INFO - 'work_dir': 'build'} 14:28:58 INFO - [mozharness: 2016-10-12 21:28:58.548697Z] Running clobber step. 14:28:58 INFO - Running pre-action listener: _resource_record_pre_action 14:28:58 INFO - Running main action method: clobber 14:28:58 INFO - rmtree: /builds/slave/test/build 14:28:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:00 INFO - Running post-action listener: _resource_record_post_action 14:29:00 INFO - [mozharness: 2016-10-12 21:29:00.610381Z] Finished clobber step (success) 14:29:00 INFO - [mozharness: 2016-10-12 21:29:00.610502Z] Running read-buildbot-config step. 14:29:00 INFO - Running pre-action listener: _resource_record_pre_action 14:29:00 INFO - Running main action method: read_buildbot_config 14:29:00 INFO - Using buildbot properties: 14:29:00 INFO - { 14:29:00 INFO - "project": "", 14:29:00 INFO - "product": "firefox", 14:29:00 INFO - "script_repo_revision": "production", 14:29:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:29:00 INFO - "repository": "", 14:29:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:29:00 INFO - "buildid": "20161012121646", 14:29:00 INFO - "pgo_build": "False", 14:29:00 INFO - "basedir": "/builds/slave/test", 14:29:00 INFO - "buildnumber": 138, 14:29:00 INFO - "slavename": "t-yosemite-r7-0333", 14:29:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:29:00 INFO - "platform": "yosemite_r7", 14:29:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:29:00 INFO - "branch": "mozilla-central", 14:29:00 INFO - "repo_path": "mozilla-central", 14:29:00 INFO - "revision": "38171e4619fb49c80f0342b6c4a91970c5faccf5", 14:29:00 INFO - "stage_platform": "macosx64", 14:29:00 INFO - "builduid": "3d8cabcf75cf4b86a7d85127993de3e1", 14:29:00 INFO - "slavebuilddir": "test" 14:29:00 INFO - } 14:29:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:29:00 INFO - Running post-action listener: _resource_record_post_action 14:29:00 INFO - [mozharness: 2016-10-12 21:29:00.613898Z] Finished read-buildbot-config step (success) 14:29:00 INFO - [mozharness: 2016-10-12 21:29:00.614010Z] Running download-and-extract step. 14:29:00 INFO - Running pre-action listener: _resource_record_pre_action 14:29:00 INFO - Running main action method: download_and_extract 14:29:00 INFO - mkdir: /builds/slave/test/build/tests 14:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:00 INFO - trying https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:00 INFO - Downloading https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 14:29:01 INFO - Downloaded 1199 bytes. 14:29:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:01 INFO - Using the following test package requirements: 14:29:01 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:29:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:29:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:29:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'jsshell-mac.zip'], 14:29:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:29:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:29:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:29:01 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:29:01 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:29:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:01 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:29:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:29:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:29:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:29:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:29:02 INFO - Http code: 200 14:29:02 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 14:29:02 INFO - x-amz-cf-id: Ooz4Lx0uI92KC7Ns0s-s00ePnW8acDyQpMUjEeGpCKnyKQTq4mKgrg== 14:29:02 INFO - x-amz-version-id: Mwd.RAKNDUXvnRCgYXISiUYCRWNMLxy_ 14:29:02 INFO - Expected file size: 16510586 14:29:02 INFO - Obtained file size: 16510586 14:29:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:29:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:29:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:29:09 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:29:09 INFO - Http code: 200 14:29:09 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 14:29:09 INFO - x-amz-cf-id: 1r3Sy2hwLXVb_1fEBWrfIu9IpgMq2KfIlAXWqWur_8K7sXmmckCxIQ== 14:29:09 INFO - x-amz-version-id: Mwd.RAKNDUXvnRCgYXISiUYCRWNMLxy_ 14:29:10 INFO - Expected file size: 16510586 14:29:10 INFO - Obtained file size: 16510586 14:29:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:29:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:29:16 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:29:17 INFO - Http code: 200 14:29:17 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 14:29:17 INFO - x-amz-cf-id: VUnDe-L2Yr7Tu1z-i4Ta8eNEldT-TDOH1xL6BJn0Ch9U7NDqZkNFyg== 14:29:17 INFO - x-amz-version-id: j03pzTwMLEnb5LsBFP3a0eV8mPX4zJaL 14:29:17 INFO - Expected file size: 14409006 14:29:17 INFO - Obtained file size: 14409006 14:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:18 INFO - trying https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 14:29:21 INFO - Downloaded 111720131 bytes. 14:29:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:21 INFO - mkdir: /builds/slave/test/properties 14:29:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:21 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:21 INFO - Contents: 14:29:21 INFO - build_url:https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:22 INFO - Running post-action listener: _resource_record_post_action 14:29:22 INFO - Running post-action listener: set_extra_try_arguments 14:29:22 INFO - [mozharness: 2016-10-12 21:29:22.230237Z] Finished download-and-extract step (success) 14:29:22 INFO - [mozharness: 2016-10-12 21:29:22.230403Z] Running populate-webroot step. 14:29:22 INFO - Running pre-action listener: _resource_record_pre_action 14:29:22 INFO - Running main action method: populate_webroot 14:29:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:29:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:29:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:29:22 INFO - '--tppagecycles', 14:29:22 INFO - '7'], 14:29:22 INFO - 'tests': ['tsvgm']}}, 14:29:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:29:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:29:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:29:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:29:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['damp', 'tps']}, 14:29:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'tests': ['damp', 'tps']}, 14:29:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['dromaeo_dom']}, 14:29:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:29:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['basic_compositor_video']}, 14:29:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:29:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'other-e10s': {'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['a11yr', 14:29:22 INFO - 'ts_paint', 14:29:22 INFO - 'tpaint', 14:29:22 INFO - 'sessionrestore', 14:29:22 INFO - 'sessionrestore_no_auto_restore', 14:29:22 INFO - 'tabpaint']}, 14:29:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['tsvgx', 14:29:22 INFO - 'tsvgr_opacity', 14:29:22 INFO - 'tart', 14:29:22 INFO - 'tscrollx', 14:29:22 INFO - 'cart']}, 14:29:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:29:22 INFO - 'tsvgr_opacity', 14:29:22 INFO - 'tart', 14:29:22 INFO - 'tscrollx', 14:29:22 INFO - 'cart']}, 14:29:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'talos_options': ['--disable-e10s'], 14:29:22 INFO - 'tests': ['tp5o']}, 14:29:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'tests': ['tp5o']}, 14:29:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'talos_options': ['--disable-e10s', 14:29:22 INFO - '--xperf_path', 14:29:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:22 INFO - 'tests': ['tp5n']}, 14:29:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:22 INFO - 'talos_options': ['--xperf_path', 14:29:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:22 INFO - 'tests': ['tp5n']}}, 14:29:22 INFO - 'talos.zip': {'path': '', 14:29:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:29:22 INFO - Running post-action listener: _resource_record_post_action 14:29:22 INFO - [mozharness: 2016-10-12 21:29:22.249051Z] Finished populate-webroot step (success) 14:29:22 INFO - [mozharness: 2016-10-12 21:29:22.249187Z] Running create-virtualenv step. 14:29:22 INFO - Running pre-action listener: _resource_record_pre_action 14:29:22 INFO - Running main action method: create_virtualenv 14:29:22 INFO - Creating virtualenv /builds/slave/test/build/venv 14:29:22 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 14:29:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:29:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:29:22 INFO - Using real prefix '/tools/python27' 14:29:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:29:23 INFO - Installing distribute.............................................................................................................................................................................................done. 14:29:25 INFO - Installing pip.................done. 14:29:25 INFO - Return code: 0 14:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:29:26 INFO - Reading from file tmpfile_stdout 14:29:26 INFO - Output received: 14:29:26 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) 14:29:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:29:26 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')]} 14:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:26 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')]} 14:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:26 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:26 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 14:29:26 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 14:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:26 INFO - 'HOME': '/Users/cltbld', 14:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:26 INFO - 'LOGNAME': 'cltbld', 14:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:26 INFO - 'MOZ_NO_REMOTE': '1', 14:29:26 INFO - 'NO_EM_RESTART': '1', 14:29:26 INFO - 'PAGER': '/bin/cat', 14:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:26 INFO - 'PWD': '/builds/slave/test', 14:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:26 INFO - 'SHELL': '/bin/bash', 14:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:26 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:26 INFO - 'USER': 'cltbld', 14:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:26 INFO - 'XPC_FLAGS': '0x0', 14:29:26 INFO - 'XPC_SERVICE_NAME': '0', 14:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:26 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 14:29:26 INFO - Cleaning up... 14:29:26 INFO - Return code: 0 14:29:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:29:26 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')]} 14:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:26 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')]} 14:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:26 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:26 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 14:29:26 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 14:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:26 INFO - 'HOME': '/Users/cltbld', 14:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:26 INFO - 'LOGNAME': 'cltbld', 14:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:26 INFO - 'MOZ_NO_REMOTE': '1', 14:29:26 INFO - 'NO_EM_RESTART': '1', 14:29:26 INFO - 'PAGER': '/bin/cat', 14:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:26 INFO - 'PWD': '/builds/slave/test', 14:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:26 INFO - 'SHELL': '/bin/bash', 14:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:26 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:26 INFO - 'USER': 'cltbld', 14:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:26 INFO - 'XPC_FLAGS': '0x0', 14:29:26 INFO - 'XPC_SERVICE_NAME': '0', 14:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:26 INFO - Downloading/unpacking psutil>=3.1.1 14:29:26 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 14:29:26 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 14:29:26 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 14:29:26 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 14:29:26 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 14:29:26 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 14:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:30 INFO - Installing collected packages: psutil 14:29:30 INFO - Running setup.py install for psutil 14:29:30 INFO - building 'psutil._psutil_osx' extension 14:29:30 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 14:29:30 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 14:29:30 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 14:29:30 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 14:29:31 INFO - building 'psutil._psutil_posix' extension 14:29:31 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 14:29:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:29:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:29:31 INFO - ^ 14:29:31 INFO - 1 warning generated. 14:29:31 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 14:29:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:31 INFO - Successfully installed psutil 14:29:31 INFO - Cleaning up... 14:29:31 INFO - Return code: 0 14:29:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:29:31 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')]} 14:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:31 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')]} 14:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:29: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 mozsystemmonitor==0.3 14:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:31 INFO - 'HOME': '/Users/cltbld', 14:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:31 INFO - 'LOGNAME': 'cltbld', 14:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:31 INFO - 'MOZ_NO_REMOTE': '1', 14:29:31 INFO - 'NO_EM_RESTART': '1', 14:29:31 INFO - 'PAGER': '/bin/cat', 14:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:31 INFO - 'PWD': '/builds/slave/test', 14:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:31 INFO - 'SHELL': '/bin/bash', 14:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:31 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:31 INFO - 'USER': 'cltbld', 14:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:31 INFO - 'XPC_FLAGS': '0x0', 14:29:31 INFO - 'XPC_SERVICE_NAME': '0', 14:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:29:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:29:34 INFO - Installing collected packages: mozsystemmonitor 14:29:34 INFO - Running setup.py install for mozsystemmonitor 14:29:34 INFO - Successfully installed mozsystemmonitor 14:29:34 INFO - Cleaning up... 14:29:34 INFO - Return code: 0 14:29:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:29:34 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')]} 14:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:34 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')]} 14:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:34 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:34 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 14:29:34 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 14:29:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:34 INFO - 'HOME': '/Users/cltbld', 14:29:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:34 INFO - 'LOGNAME': 'cltbld', 14:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:34 INFO - 'MOZ_NO_REMOTE': '1', 14:29:34 INFO - 'NO_EM_RESTART': '1', 14:29:34 INFO - 'PAGER': '/bin/cat', 14:29:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:34 INFO - 'PWD': '/builds/slave/test', 14:29:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:34 INFO - 'SHELL': '/bin/bash', 14:29:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:34 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:34 INFO - 'USER': 'cltbld', 14:29:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:34 INFO - 'XPC_FLAGS': '0x0', 14:29:34 INFO - 'XPC_SERVICE_NAME': '0', 14:29:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:34 INFO - Downloading/unpacking jsonschema==2.5.1 14:29:34 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 14:29:34 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 14:29:34 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 14:29:34 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 14:29:34 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 14:29:34 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 14:29:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:29:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 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 14:29:37 INFO - Downloading functools32-3.2.3-2.tar.gz 14:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:29:37 INFO - warning: no files found matching '*.txt' 14:29:37 INFO - no previously-included directories found matching 'build' 14:29:37 INFO - no previously-included directories found matching 'dist' 14:29:37 INFO - no previously-included directories found matching '.git*' 14:29:37 INFO - Installing collected packages: jsonschema, functools32 14:29:37 INFO - Running setup.py install for functools32 14:29:37 INFO - warning: no files found matching '*.txt' 14:29:37 INFO - no previously-included directories found matching 'build' 14:29:37 INFO - no previously-included directories found matching 'dist' 14:29:37 INFO - no previously-included directories found matching '.git*' 14:29:37 INFO - Successfully installed jsonschema functools32 14:29:37 INFO - Cleaning up... 14:29:37 INFO - Return code: 0 14:29:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:29:37 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')]} 14:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:37 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')]} 14:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:37 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:37 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 14:29:37 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 14:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:37 INFO - 'HOME': '/Users/cltbld', 14:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:37 INFO - 'LOGNAME': 'cltbld', 14:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:37 INFO - 'MOZ_NO_REMOTE': '1', 14:29:37 INFO - 'NO_EM_RESTART': '1', 14:29:37 INFO - 'PAGER': '/bin/cat', 14:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:37 INFO - 'PWD': '/builds/slave/test', 14:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:37 INFO - 'SHELL': '/bin/bash', 14:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:37 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:37 INFO - 'USER': 'cltbld', 14:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:37 INFO - 'XPC_FLAGS': '0x0', 14:29:37 INFO - 'XPC_SERVICE_NAME': '0', 14:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:29:38 INFO - Cleaning up... 14:29:38 INFO - Return code: 0 14:29:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:29:38 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')]} 14:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:38 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')]} 14:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:38 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:38 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 14:29:38 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 14:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:38 INFO - 'HOME': '/Users/cltbld', 14:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:38 INFO - 'LOGNAME': 'cltbld', 14:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:38 INFO - 'MOZ_NO_REMOTE': '1', 14:29:38 INFO - 'NO_EM_RESTART': '1', 14:29:38 INFO - 'PAGER': '/bin/cat', 14:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:38 INFO - 'PWD': '/builds/slave/test', 14:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:38 INFO - 'SHELL': '/bin/bash', 14:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:38 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:38 INFO - 'USER': 'cltbld', 14:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:38 INFO - 'XPC_FLAGS': '0x0', 14:29:38 INFO - 'XPC_SERVICE_NAME': '0', 14:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:38 INFO - Downloading/unpacking blobuploader==1.2.4 14:29:38 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 14:29:38 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 14:29:38 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 14:29:38 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 14:29:38 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 14:29:38 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 14:29:40 INFO - Downloading blobuploader-1.2.4.tar.gz 14:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:29:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:29:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 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 14:29:42 INFO - Downloading docopt-0.6.1.tar.gz 14:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:29:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:29:42 INFO - Running setup.py install for blobuploader 14:29:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:29:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:29:42 INFO - Running setup.py install for requests 14:29:42 INFO - Running setup.py install for docopt 14:29:43 INFO - Successfully installed blobuploader requests docopt 14:29:43 INFO - Cleaning up... 14:29:43 INFO - Return code: 0 14:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:43 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 14:29:43 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 14:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:43 INFO - 'HOME': '/Users/cltbld', 14:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:43 INFO - 'LOGNAME': 'cltbld', 14:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:43 INFO - 'MOZ_NO_REMOTE': '1', 14:29:43 INFO - 'NO_EM_RESTART': '1', 14:29:43 INFO - 'PAGER': '/bin/cat', 14:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:43 INFO - 'PWD': '/builds/slave/test', 14:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:43 INFO - 'SHELL': '/bin/bash', 14:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:43 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:43 INFO - 'USER': 'cltbld', 14:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:43 INFO - 'XPC_FLAGS': '0x0', 14:29:43 INFO - 'XPC_SERVICE_NAME': '0', 14:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-A8futN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Gn6X3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-V4cmjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ENIsHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Hq58Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:43 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-w2RJfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-OqDLT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-6KRqty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-barDP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ufuvWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Y_5DkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-WWzb5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-sp5wmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-N04pdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:44 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-IO7WBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:45 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-_n_UzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:45 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-VrZver-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:29:45 INFO - Running setup.py install for manifestparser 14:29:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozcrash 14:29:45 INFO - Running setup.py install for mozdebug 14:29:45 INFO - Running setup.py install for mozdevice 14:29:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:29:45 INFO - Running setup.py install for mozfile 14:29:45 INFO - Running setup.py install for mozhttpd 14:29:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozinfo 14:29:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozInstall 14:29:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozleak 14:29:46 INFO - Running setup.py install for mozlog 14:29:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for moznetwork 14:29:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:29:46 INFO - Running setup.py install for mozprocess 14:29:47 INFO - Running setup.py install for mozprofile 14:29:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Running setup.py install for mozrunner 14:29:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Running setup.py install for mozscreenshot 14:29:47 INFO - Running setup.py install for moztest 14:29:47 INFO - Running setup.py install for mozversion 14:29:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:29:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:29:47 INFO - Cleaning up... 14:29:47 INFO - Return code: 0 14:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:47 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')]} 14:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:47 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')]} 14:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:47 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:47 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 14:29:47 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 14:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:47 INFO - 'HOME': '/Users/cltbld', 14:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:47 INFO - 'LOGNAME': 'cltbld', 14:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:47 INFO - 'MOZ_NO_REMOTE': '1', 14:29:47 INFO - 'NO_EM_RESTART': '1', 14:29:47 INFO - 'PAGER': '/bin/cat', 14:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:47 INFO - 'PWD': '/builds/slave/test', 14:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:47 INFO - 'SHELL': '/bin/bash', 14:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:47 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:47 INFO - 'USER': 'cltbld', 14:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:47 INFO - 'XPC_FLAGS': '0x0', 14:29:47 INFO - 'XPC_SERVICE_NAME': '0', 14:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-WsED9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-toZHjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-vRuBoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-S4AzR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-C22Zi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-kIuDWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-4oDbLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-FpQhh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:48 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)) 14:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:48 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-2X5oAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ghCtxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-hMO6U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Eg_wmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9xyxjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-PihWkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ggqbjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-X29n1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:49 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)) 14:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:49 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Vue9Ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:49 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)) 14:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:49 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)) 14:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:49 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 14:29:49 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 14:29:49 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 14:29:49 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 14:29:49 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 14:29:49 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 14:29:52 INFO - Downloading blessings-1.6.tar.gz 14:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:29:52 INFO - Installing collected packages: blessings 14:29:52 INFO - Running setup.py install for blessings 14:29:52 INFO - Successfully installed blessings 14:29:52 INFO - Cleaning up... 14:29:52 INFO - Return code: 0 14:29:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:29:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:52 INFO - Reading from file tmpfile_stdout 14:29:52 INFO - Current package versions: 14:29:52 INFO - blessings == 1.6 14:29:52 INFO - blobuploader == 1.2.4 14:29:52 INFO - docopt == 0.6.1 14:29:52 INFO - functools32 == 3.2.3-2 14:29:52 INFO - jsonschema == 2.5.1 14:29:52 INFO - manifestparser == 1.1 14:29:52 INFO - mozInstall == 1.12 14:29:52 INFO - mozcrash == 0.17 14:29:52 INFO - mozdebug == 0.1 14:29:52 INFO - mozdevice == 0.48 14:29:52 INFO - mozfile == 1.2 14:29:52 INFO - mozhttpd == 0.7 14:29:52 INFO - mozinfo == 0.9 14:29:52 INFO - mozleak == 0.1 14:29:52 INFO - mozlog == 3.4 14:29:52 INFO - moznetwork == 0.27 14:29:52 INFO - mozprocess == 0.23 14:29:52 INFO - mozprofile == 0.28 14:29:52 INFO - mozrunner == 6.12 14:29:52 INFO - mozscreenshot == 0.1 14:29:52 INFO - mozsystemmonitor == 0.3 14:29:52 INFO - moztest == 0.7 14:29:52 INFO - mozversion == 1.4 14:29:53 INFO - psutil == 3.1.1 14:29:53 INFO - requests == 1.2.3 14:29:53 INFO - wsgiref == 0.1.2 14:29:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:53 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 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:53 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 14:29:53 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 14:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:53 INFO - 'HOME': '/Users/cltbld', 14:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:53 INFO - 'LOGNAME': 'cltbld', 14:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:53 INFO - 'MOZ_NO_REMOTE': '1', 14:29:53 INFO - 'NO_EM_RESTART': '1', 14:29:53 INFO - 'PAGER': '/bin/cat', 14:29:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:53 INFO - 'PWD': '/builds/slave/test', 14:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:53 INFO - 'SHELL': '/bin/bash', 14:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:53 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:53 INFO - 'USER': 'cltbld', 14:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:53 INFO - 'XPC_FLAGS': '0x0', 14:29:53 INFO - 'XPC_SERVICE_NAME': '0', 14:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 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)) 14:29:53 INFO - Cleaning up... 14:29:54 INFO - Return code: 0 14:29:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101973570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd03ddc890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, '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.IePIKDmaKG/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.4GQ79RMD0b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 14:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:54 INFO - 'HOME': '/Users/cltbld', 14:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:54 INFO - 'LOGNAME': 'cltbld', 14:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:54 INFO - 'MOZ_NO_REMOTE': '1', 14:29:54 INFO - 'NO_EM_RESTART': '1', 14:29:54 INFO - 'PAGER': '/bin/cat', 14:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:54 INFO - 'PWD': '/builds/slave/test', 14:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:54 INFO - 'SHELL': '/bin/bash', 14:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:29:54 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:54 INFO - 'USER': 'cltbld', 14:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:54 INFO - 'XPC_FLAGS': '0x0', 14:29:54 INFO - 'XPC_SERVICE_NAME': '0', 14:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:29:54 INFO - Cleaning up... 14:29:54 INFO - Return code: 0 14:29:54 INFO - Running post-action listener: _resource_record_post_action 14:29:54 INFO - Running post-action listener: _start_resource_monitoring 14:29:54 INFO - Starting resource monitoring. 14:29:54 INFO - [mozharness: 2016-10-12 21:29:54.707136Z] Finished create-virtualenv step (success) 14:29:54 INFO - [mozharness: 2016-10-12 21:29:54.708015Z] Running install step. 14:29:54 INFO - Running pre-action listener: _resource_record_pre_action 14:29:54 INFO - Running main action method: install 14:29:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:54 INFO - Reading from file tmpfile_stdout 14:29:54 INFO - Detecting whether we're running mozinstall >=1.0... 14:29:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:29:55 INFO - Reading from file tmpfile_stdout 14:29:55 INFO - Output received: 14:29:55 INFO - Usage: mozinstall [options] installer 14:29:55 INFO - Options: 14:29:55 INFO - -h, --help show this help message and exit 14:29:55 INFO - -d DEST, --destination=DEST 14:29:55 INFO - Directory to install application into. [default: 14:29:55 INFO - "/builds/slave/test"] 14:29:55 INFO - --app=APP Application being installed. [default: firefox] 14:29:55 INFO - mkdir: /builds/slave/test/build/application 14:29:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:30:29 INFO - Reading from file tmpfile_stdout 14:30:29 INFO - Output received: 14:30:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:30:29 INFO - Running post-action listener: _resource_record_post_action 14:30:29 INFO - [mozharness: 2016-10-12 21:30:29.835759Z] Finished install step (success) 14:30:29 INFO - [mozharness: 2016-10-12 21:30:29.835888Z] Running run-tests step. 14:30:29 INFO - Running pre-action listener: _resource_record_pre_action 14:30:29 INFO - Running main action method: run_tests 14:30:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:30:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:30:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:30:29 INFO - 2016-10-12 14:30:29.848 screenresolution[1642:7589] starting screenresolution argv=screenresolution get 14:30:29 INFO - 2016-10-12 14:30:29.851 screenresolution[1642:7589] Display 0: 1600x1200x32@60 14:30:29 INFO - 2016-10-12 14:30:29.856 screenresolution[1643:7591] starting screenresolution argv=screenresolution list 14:30:29 INFO - Available Modes on Display 0 14:30:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:30:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:30:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:30:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:30:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:30:30 INFO - Intel Iris: 14:30:30 INFO - Chipset Model: Intel Iris 14:30:30 INFO - Type: GPU 14:30:30 INFO - Bus: Built-In 14:30:30 INFO - VRAM (Dynamic, Max): 1536 MB 14:30:30 INFO - Vendor: Intel (0x8086) 14:30:30 INFO - Device ID: 0x0a2e 14:30:30 INFO - Revision ID: 0x0009 14:30:30 INFO - Displays: 14:30:30 INFO - Display: 14:30:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:30:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:30:30 INFO - Display Serial Number: 1600x1200 60 14:30:30 INFO - Main Display: Yes 14:30:30 INFO - Mirror: Off 14:30:30 INFO - Online: Yes 14:30:30 INFO - Rotation: Supported 14:30:30 INFO - Return code: 0 14:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:30 INFO - Python 2.7.3 14:30:30 INFO - Return code: 0 14:30:30 INFO - grabbing minidump binary from tooltool 14:30: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')]} 14:30:30 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 0x101811d30>, '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 0x101637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101809b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:30 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 14:30:30 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 14:30:30 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 14:30:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:30 INFO - Return code: 0 14:30:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:30:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:30:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:30 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/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 14:30:30 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/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0333 --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 14:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IePIKDmaKG/Render', 14:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:30 INFO - 'HOME': '/Users/cltbld', 14:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:30 INFO - 'LOGNAME': 'cltbld', 14:30:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:30 INFO - 'MOZ_NO_REMOTE': '1', 14:30:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:30 INFO - 'NO_EM_RESTART': '1', 14:30:30 INFO - 'PAGER': '/bin/cat', 14:30:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:30 INFO - 'PWD': '/builds/slave/test', 14:30:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:30 INFO - 'SHELL': '/bin/bash', 14:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GQ79RMD0b/Listeners', 14:30:30 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 14:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:30 INFO - 'USER': 'cltbld', 14:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:30 INFO - 'XPC_FLAGS': '0x0', 14:30:30 INFO - 'XPC_SERVICE_NAME': '0', 14:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:30 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/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 14:30:30 INFO - mozversion application_buildid: 20161012121646 14:30:30 INFO - mozversion application_changeset: 38171e4619fb49c80f0342b6c4a91970c5faccf5 14:30:30 INFO - mozversion application_display_name: Nightly 14:30:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:30 INFO - mozversion application_name: Firefox 14:30:30 INFO - mozversion application_remotingname: firefox 14:30:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:30:30 INFO - mozversion application_vendor: Mozilla 14:30:30 INFO - mozversion application_version: 52.0a1 14:30:30 INFO - mozversion platform_buildid: 20161012121646 14:30:30 INFO - mozversion platform_changeset: 38171e4619fb49c80f0342b6c4a91970c5faccf5 14:30:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:30:30 INFO - mozversion platform_version: 52.0a1 14:30:30 INFO - using testdate: 1476307830 14:30:30 INFO - actual date: 1476307830 14:30:30 INFO - starting webserver on 'localhost:49268' 14:30:30 INFO - SUITE-START | Running 2 tests 14:30:30 INFO - TEST-START | dromaeo_css 14:30:30 INFO - operating with platform_type : mac_ 14:30:30 INFO - Initialising browser for dromaeo_css test... 14:30:30 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp7oNvWQ/profile http://localhost:49268/getInfo.html) 14:30:33 INFO - PROCESS | 1653 | 2016-10-12 14:30:33.285 plugin-container[1655:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 14:30:33 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:33 INFO - PROCESS | 1653 | 2016-10-12 14:30:33.288 plugin-container[1655:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 14:30:33 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:33 INFO - PROCESS | 1653 | 2016-10-12 14:30:33.288 plugin-container[1655:7764] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:30:33 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 14:30:33 INFO - PROCESS | 1653 | colorDepth:24 14:30:33 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 14:30:33 INFO - PROCESS | 1653 | __metrics 14:30:33 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 14:30:34 INFO - TEST-INFO | 1653: exit 0 14:30:34 INFO - Browser initialized. 14:30:34 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:34 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp7oNvWQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:36 INFO - PROCESS | 1656 | 2016-10-12 14:30:36.616 plugin-container[1657:7887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 14:30:36 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:36 INFO - PROCESS | 1656 | 2016-10-12 14:30:36.616 plugin-container[1657:7887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 14:30:36 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:36 INFO - PROCESS | 1656 | 2016-10-12 14:30:36.616 plugin-container[1657:7887] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:30:46 INFO - PROCESS | 1656 | RSS: Main: 239005696 14:30:46 INFO - PROCESS | 1656 | 14:31:35 INFO - PROCESS | 1656 | 1476307895982 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] 14:33:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 14:33:51 INFO - PROCESS | 1656 | RSS: Main: 250998784 14:33:51 INFO - PROCESS | 1656 | 14:36:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 14:36:53 INFO - PROCESS | 1656 | RSS: Main: 265560064 14:36:53 INFO - PROCESS | 1656 | 14:39:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 14:39:56 INFO - PROCESS | 1656 | RSS: Main: 270118912 14:39:56 INFO - PROCESS | 1656 | 14:42:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 14:42:58 INFO - PROCESS | 1656 | RSS: Main: 270258176 14:42:58 INFO - PROCESS | 1656 | 14:46:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 14:46:02 INFO - PROCESS | 1656 | RSS: Main: 275185664 14:46:02 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 14:49:05 INFO - PROCESS | 1656 | RSS: Main: 277884928 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | __start_tp_report 14:49:05 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 14:49:05 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 14:49:05 INFO - PROCESS | 1656 | |i|pagename|runs| 14:49:05 INFO - PROCESS | 1656 | |0;dojo.html;3388.949165762514;3390.6948374646286;3412.2322477442563;3415.8292085395733;3422.7775194612364;5567.777288908449;5667.688277144755;5700.173474846145;5753.539716822655;5775.971120144399;4746.596539294164;5569.805056823012;5669.773209071643;5700.7719691212405;5756.8272951811405;5178.715170665612;5223.190405487146;5248.816291429801;5297.364316282033;5299.178627312773;5229.163333866582;5307.575393968474;5340.599455040868;5349;5414.10667239905;4141.689373296999;4670.019295947855;5326.067938110815;5346;5360.061989151903;5089.720065396402;5108.923366149511;5154.201098829687;5195.116830138885;5313.734313284321;5083.74581270935;5096.949030509684;5142.717150556718;5198.558122559549;5232.136697444917;5449.972750136264;5578.30271216098;5661.2357331760095;5712.485876271156;5772.7113644317615;6112.07796728157;6121.693915304217;6122.886104953791;6171.660558669271;6192.225971753531;6197.411245931629;6235.158253635792;6243.1883855099;6244.219472565929;6251.28110267316;4819.373481447425;5805;5808.970955145197;5822.534197264209;5823.737931793079;6103.004334463626;6169.074638804215;6189.319174890758;6213.813585592439;6220.595661282002;6164.044573091178;6185.103160041859;6191.84520386994;6216.036514340284;6224.751009959551;6209.5032397408095;6253.093301471261;6253.624782512973;6259;6279.058141278845;6024.728887200086;6128.3565225651555;6131.040261761288;6144.064619644798;6241.687915604201;5120.283160357553;6194.566380353332;6220;6228.439440450381;6252.812415627538;4937.679050861682;4939.1109600271575;4967.751612419364;4968.826091086795;4981.252812078144;3976.741511801724;4338.175746608197;4402.097569998143;4408.272635015187;4421.911561768747;5782.88434231313;5806.564507661942;5825.2135961644635;5839.824805255849;5840.941590584125;6213;6219.191505104308;6226.782062627786;6268.655223962726;6271.27880293763;5635.380108188096;5705.62913410627;5723.914141287884;5793.652380857162;5817.621854579439;5389.649672771257;5415.83752487426;5432.456754324536;5464.808731694451;5484.643498172606;6311.084892690534;6314.400131987454;6327.493800496041;6337.556371053982;6352.618842869535;5078.644494885322;5154.917467331902;5320.707361095177;5801.419858014165;6208.4722798562625;5372.516473517402;5713.7428815704225;6137.478314343152;6197;6247.875042499216;6124.533510047256;6232;6246.593971391845;6256.968715156395;6257.906131407942;6103.6407333109855;6108.645631960618;6118.162361256178;6135.07973803933;6230.719617617127;5447.754851031634;6116.676957396471;6117.614269788275;6142.815715528542;6152.969235153795;5887.381824908494;5986.491148252448;6189.597676151036;6203.2245969253845;6234.594751341148;5310.707911064774;5852.063669812809;5984.431479009487;6037.426444487766;6110.877782444415;6148.200733904564;6149.385061493815;6184.5980011299125;6189.288231853395;6200.131981522681;5793.623414478214;6046.758129674764;6048.092786082123;6052.455279024909;6082.300535438304 14:49:05 INFO - PROCESS | 1656 | |1;ext.html;468.46126954002915;471.98348057817975;478.5931957835838;510.50991048593346;514.8018013064969;40186.397204041925;42075.57924420751;42132.157356852884;44236.67289981306;44411;42669.573304266945;43628.345574816405;43683.12633747331;43696.78151609238;43717.562824371744;9343.112404321595;9620.230381569492;9625.37435066722;9649.421034737903;9671.177949874234;9476.099770521789;9557.808843823119;9564.856527152098;9589.472579008152;9592.280578956572;9525.618975240983;9602.615895364177;9619.855702164474;9629.662961796339;9646.951765241163;9446.055394460567;9528.475933823636;9547.379420337656;9550.188234000083;9576.952115239379;9354;9391.62433502659;9424.811503769886;9492.857607135898;9525.523723813782;40837.591624083674;41032;41168.58831411708;41320.1735965279;41597.584024159674;3343.264481813997;3365.1923538350866;3544.043108360754;3684.207895302523;3993.6405723484736;30849.691503084905;31162.688373116205;31307.373852522822;31609.367812643617;32207.87272445453;3196.5844440222854;3218.903432897017;3227.6126864776143;3279.8688052477873;3335.0995231287416;483688;483713;488381;488647.55676221394;490456;61805.38194618086;62099;62145.378546214866;62229;62288.688556556925;64430;64477;64576;64678.353216467236;65007.349926501076;14900.62748431298;14951.252437378087;15077.623059423382;15157.772633410508;15217.934744567774;12705.745885082246;12837.229766214057;12840.165389249669;12853.742925141445;12885.677858053623;17362.913185434245;17475.038872862115;17519.124043797758;17620.735688964676;17651.32312430317;6999.685014174374;7010.614416207158;7015.228324884259;7058.9294107059295;7146.678399472036;708.0618180910315;839.5214727605206;921.428714197202;1021.4626986385442;1028.4292217819032;23667.408314791923;30250.84874575657;30313.848430757706;30360.696393035785;30788.538171927437;1004.633308842282;1020.550957578663;1055.883852776194;1088.4013792414141;1134.6652737442473;13557.051006429456;14041.017128800886;15264.542063738105;15765.763513547307;15881.538361553747;612803;618136;618303;622350;628628;57087.714561427754;57090.14364784532;57357.42642573604;57404;57590.42409575934;1290.6708789258678;1332.6934804994905;1336.4520546575857;1344.3748656874404;1350.5678182981549;23908.372953510006;25900.35249118787;29079.273018174714;29212.853935731036;29645.555316669837;49378;49969.50030499649;50275.24587131123;50363;50459;595580.4419558183;599075;600199.9990000022;600486;601458;39790;39980.400293996194;40234.597654024255;40384.59615403808;40810.59189408187;46697.83255418642;46972;47972.040559187924;48135.518644813106;48138.75930620325;37901.81049094737;37945.81027094847;38058.602225451716;39103.60896391114;39678.404823927085;36443.81778091199;37572.060698482754;38792.80603596964;39092.56834503847;39877.601223988546;37414;37575.43636845395;38220.235595287384;38342.041448964;39037;33025.174370640925;34242;34345;34684.82657586696;35023.824880876455;25271.87364063168;25376.238712837905;25704.971801128493;25995.480090398465;26006.60990085188 14:49:05 INFO - PROCESS | 1656 | |2;jquery.html;331.3968577189515;359.68707224714484;365.97255205859557;371.2963933346308;378.2133163020917;18545.258189672415;18836.717449238255;20142.19431222753;20188.59622807543;20444.693329600068;15280.84719152811;19588.608227835433;19603.5099122522;19886.303979360724;19895.30366437175;5567.4432556744305;5754.280714910637;5833.620814647055;5873.530117590604;5884.117382392654;5420.268263784398;5762.164486149527;5813.854653633651;5821.854453638651;5822.7088645567765;5492.395836458007;5755.856103597423;5768.913466297988;5787.450192231731;5812.041013232812;4689.273162659793;4925.31045653607;5181.378234611833;5610.074337734269;5770.105633626795;4698.906021879578;4792.640551958617;5017.54842064216;5021.221710634857;5023.648344615878;22465.55068898629;22826.42933926665;23043.30870073884;23383.298501044996;23415.76584234153;2454.950900981988;2590.8315959462575;2621.554335762925;2739.671239451279;3037.1040543039635;15125.243737813065;15133.092014479167;15280.235988200657;15482.922585387001;15985.680286394323;1857.8606604504716;1912.3020097664391;1933.6132773445227;1948.074664534349;2068.917243310266;89731.10268897293;108307.91692083135;121492;136501.31749341288;138849;19142.521436963907;19658;19697.606047878962;20139.19443222284;20176.29382971589;33409.49885751716;33572.83213583916;33616.99149025533;33690.98927032193;33884.83057584745;9386.530673466299;10191.847122293171;10329.431881246453;10628.521716522775;10629.25595208328;8720.8691869622;8763.956180219058;8829.646814127364;8940.46357218569;8959.462432254086;7709.7301594443925;9287.349885508085;9355.29835262358;9470;9470.242380609534;3813.370793819044;3849.3071247175217;3858.9421158682644;3885.4171874218528;3901.843926242975;700.3556727810427;787.5943888897223;808.2442915873677;832.8042909916165;848.5841937450605;18923.05384730758;19050.428487145407;20500.89749551243;20934.685979710317;21199.046042928105;870.425519157353;881.1805021330224;893.8659201119754;912.3020889019905;926.749777560055;10555.94722026385;10939.453027348602;10984.835227471594;10985.725356866142;11058.944705276423;596501;602276;609717;613820;624972;105271.36821579521;122037.38981305214;125362.74637253693;127810.36094819652;130114.34942825415;958.5399008475971;992.1814503035025;1030.0986636692894;1030.907218350352;1031.9793604127876;18859.811401886356;18870.056497174814;18956.0521973898;19217.231310747415;19388.030598469737;39673;52026.47973520368;52821.7358913203;53368.73315633397;54755.72622136864;62653.554054525615;72674;78028;80034.79947800671;80997;6120.816375508742;7643.961780191064;7776.494527855672;7852.882206766789;7854.764357069297;9399.436033837992;11064;12197.75604487923;12445.635329287406;12481.126321157431;8187.1403502631365;9094.772630684285;10732.51703673321;10950.123990080934;11030.724231893946;10355.534000970125;10406.323588966692;10495.842562361724;10729.9463502682;10731.087857532197;8989.865152022856;10844;10872.782544348911;11035.44822758875;11107.777844443228;6793.354631310018;7105.18290396611;7163.2478589747325;7728.497647652885;8077.434579579373;10567.260291779809;10654.094401975919;10880.945595271973;11064.778704425706;11306.208565400342 14:49:05 INFO - PROCESS | 1656 | |3;mootools.html;1325.5956933135392;1350.2776014832064;1623.8863279570428;1816.9636607267855;1858.702607582787;7846.960765196166;8067.556284404355;8285.875711864319;8881.378303518757;8905.4211476254;8320.251177394033;8641.52471614061;8659.69691060813;8808.207261346477;8851.690190843306;2301.4591570980765;2314.849534780234;2335.9182428615004;2338.2634470141934;2338.6141286687675;1971.300188433107;2285.6228722260807;2295.6900818389554;2344.8475849069755;2362.492064206195;2302.9654555181687;2303.527776805759;2306.1582522379376;2330.848494847838;2345.355027367479;2289.061484791236;2292.3695983604475;2294.4952110535655;2297.3911913342977;2303.57383883982;2307.48081681622;2310.722713274418;2313.6298192289323;2314.780095890888;2322.6283794592955;7297.343239108506;8850.822983540358;8919.375643704943;8924.375293729441;9219.67731129414;5173.275741396207;5247.845473995715;5330.653507521999;5338.932213557266;5351.170568561879;2989.880404783806;3388.745844061705;3443.4490481522835;3562.8218589070443;3578.910527236814;930.5579849571467;1072.1101485766874;1073.3398959639844;1091.3888222595292;1099.115212254135;9987.700368988943;11493.425328733614;11526.308421494738;11579.652610421701;11690.824637630527;4553.817847286137;4591.95408045922;4786.210275304606;4787.521247875255;4791.185498465269;8877.955610222038;9451.865776106773;9837.557309920927;9856.310058295994;9862.211023118134;5293.126634105406;5313.149896016619;5316.202569614588;5319.521243088141;5333.759980800874;4300.247456695066;4432.84485043022;4694.201985662446;4813.302071199656;4821.9035619288115;3806.885793426202;3851.7111216658864;3929.2730844793805;3931.744436611611;3988.564116917907;1110.9833352499718;1273.6624794429483;1279.6289076167807;1280.6030130659526;1293.5278623302427;739.1979702023258;843.7131375332293;907.4237858959608;908.5230254116642;931.743770463128;5679.545636349082;5767.798127065533;5873.970630146822;5880.823835232971;6095.725692343855;869.573908784691;946.9005754395827;947.6495993209786;958.769895225141;991.8710567626163;3597.8740744074357;3906.43356713275;3909.843606255775;4023.1752490739327;4024.5774193709826;9221.631134754669;10300;10740.248182627298;10744.247902646895;10786;8151.918480815235;10494.003069708366;10620.787584248272;10711.410872401937;10752.569897204025;7566.583837888859;7851.960740196148;8072.4349295548745;8236.094029656733;8246.175382461826;5830.82507524775;6086.744080531742;6098.390160983866;6141.324454310112;6257.43683068526;9632.518374081128;9988.400695958266;10029.598816047277;10057.547410366406;10073.19414446857;9662.71011869617;9795.559199835881;10044.347117437635;10136.847947280945;10210.336328138646;6792.218894827158;7000.859982800417;7171.533850299714;7277.090363704537;7524.699012039458;8144.877826832484;8320.875186872081;8324.62539185726;8349.33205343559;8360.331173506227;7430.7027718892805;7431.554106753612;7432.070991126109;7554.471187016856;7706.653200606098;7337.0828646419195;7387.741429050173;7448.255174482725;7511.474196806171;7582.54504729718;5930.258717660293;7047.260037695968;7074.893876591752;7076.0801095857205;7094.6097964612445;4911.533404326583;4953.504649535018;5073.492650734897;5092.363454568179;5105.2086926526445;4775.928361074517;4897.240927656219;5001.349824522788;5034.874128146826;5156.4070131935305 14:49:05 INFO - PROCESS | 1656 | |4;prototype.html;297.98510074496284;299.4265980647061;307.60780005493;314.4465740297076;315.0611178687511;4867.634927380443;5394.838154855351;5486.835394938148;5612.270404847369;5633.746481408336;4940.876478088049;5456.7544460499275;5460.389185190368;5532.087205611079;5568.303962004749;3527.5061491391225;3717.6654101130894;4043.9595604043875;4243.408721729336;4246.299360605496;4019.276530224573;4117.64705882353;4208.347706105558;4273.722208056482;4318.632916202127;3650.4159334506485;4017.718759686823;4145.419641250227;4156.314208155666;4188.246115699173;4170.624643782059;4208;4209.473815773028;4227.513835908849;4234.957650423487;3665.6700896919406;3675.5405574303213;3687.6681098701247;3693.316736403774;3694.4458331250257;5142.357205349332;5359.124306492736;5370.516653501165;5371.597130215249;5387.4612538746205;4805.903881922342;4806.831760888352;4840.878978025577;4841.8547443576745;4861.975690121527;5525.6462166769;5603.851210501878;5645;5810.796622118206;5836.394991377363;3399;3963.7819919904396;3989.404238304672;4002.5597184309704;4014.598540145962;5433.527744876072;5856.799356131983;6101.450869421773;6190.966426451052;6270.623762574261;4082.3264161413035;4096.365063415175;4098.405731168964;4098.508179018537;4099.323611604052;3367.0572239772905;3986.3855138668887;4012.1775036117524;4109.630133288018;4164.292070348048;4063.593640635913;4779.187538118528;4784.827717209306;4811.446683631357;4825.793551612097;5900.763969441175;5901.970490147522;5936.614120082268;5937.9406205937385;5957.702114894238;1192.9642110736693;1213.7754515414676;1216.3819339976192;1286.8327117474669;1426.073052515873;774.9728759493504;779.8908152858604;801.9558924259105;874.8119154381839;876.7983363826228;4276.850310239127;4277.556566963562;4394.209042372339;4535.074876704573;4536;4903.681260718042;4949.604031677457;5009.423916249678;5024.010713625387;5033.7734801934;4093.4269202311702;4209.221294060609;4309.310510318397;4538.568835960578;4567.520410356931;2387.4747555537756;2473.9010439582216;2643.220250026247;2654.9380247900995;2703.864806759654;4389.868303950887;4599.885002874888;4890.462049174588;4909.705417674951;5041.873953151201;4965.37122940878;4997.400311962588;5124.410692770304;5161.019406312788;5188.379868166628;4906.754662266872;5137.794488220429;5147.485251474973;5191.9480805192225;5279.41926388097;4487.865364039084;4617.722936623813;4634.513376095596;5001.924971125363;5006.246564389498;4359.585839345257;4427;4721.9055618888115;4898.559129678346;4946.307516947558;4622.104936975824;4713.717176969393;4715.288708992412;5250.868728281824;5307.893842123213;3052.2827135623556;3124;3225.371187550272;3380.4253276942486;3564.964350356567;4614.561616646413;5024.497550244946;5033.043721692867;5066.746662666926;5123.590112791042;5148.665336753099;5376.139817629317;5755.625884317674;5795.33353664334;5854.268216472941;5861.32594751609;5869.53043756507;5883.529317654663;5892.705364731832;5913.852153696192;5547.306586676666;5604.218805863516;5622.2731974991275;5688.431156884279;5764.279465066867;5877.764889404376;5880.294364676266;5884.735186916513;5903.527717782481;5925.555583331181;4884.804607815648;4884.926726099183;5503.532199763065;5706.572007099568;5712.971435142797 14:49:05 INFO - PROCESS | 1656 | |5;yui.html;655.9016147577864;686.8214264291285;698.7519430602136;708.5217478202212;734.9154847192574;14828.77756833647;15204.391824327015;15503.069815811032;15756.60608484787;15952.601184970381;14181.71636567268;14187.574372768808;14191.219482928434;14346;14423.567292981228;816.4366587054928;835.8244768598571;837.0666706622134;837.5016864965336;839.4166054592062;664.4717449627558;681.41057984843;815.5310696349593;832.134580036761;849.1381248033221;845.5222799118509;882.4087861133056;887.3344991256557;887.5384799904047;888.3736965439382;844.4595458906305;878.964841406343;880.2473884828507;880.9207171354577;882.5146169606691;625.7872323410016;626.1203009771278;647.3429469088878;650.1742786660961;656.5633853487425;14512.346944387422;14535.927320363437;14797.852021479863;14814.851851481455;14872.851271487254;11439.88560114405;14077;14213.076150050318;14303.284835758275;14546.854531454655;11585.942070289679;11631.127665425127;11743.7064073398;11830.645080647595;12079.818802717966;3774.1885494618514;3780.8298626561873;3805.809709514541;3820.6943444524372;3946.8026598669953;115747;116869;118041;118904;121372;10087.848682269772;10374.585016599402;10596.152307815357;11029.44852757359;11162;16190.919045404933;17212;17477.65044699099;18752.531186720167;20023.696090145255;7565.130010048805;7611.086669599684;7688.8462230756195;7813.140554538996;7861.095973962953;4282.421873047098;4296.2696341621995;4329.328954012133;4349.108432771274;4353.412289340962;3023.410434965205;3436.9312613747584;3519.964800352015;3527.6472352764517;3533.5936367318086;659.152988409891;659.4361820643377;661.394823736276;711.4450728431832;725.6661935509619;568.6730130175166;570.5264630356794;609.6464050850585;663.1048085085193;670.792054463118;9384.67153649619;9649.614015439303;9677.758056048515;9683.426443202958;9733.350585752796;618.5793660311032;642.1390077116185;645.9806205813833;678.91852977643;689.6448329110513;4599.977000114978;4663.391130060124;4701.435827700698;4806.447258565309;4857.635677324215;103410;128108;161986;170099;173886;119293;138052;140685;146028;147100.99999999785;123300.76699233073;124396.37801810933;124692.37653811673;125005.12492312622;128022.35988820183;7276.454265930182;8498.532580707873;8929.73210803677;8949.194572488508;9097.317701172571;66792.6660366695;68671.65664171647;69223.65388173026;69404;69480;97885;103352.96647033734;104147;104247;106025;10656.680299591024;11829.76340473203;11870.109741769229;11953.820692689442;12120.333381664093;12882.291473968648;13191.67020824487;13229.272390018641;13464.461421543421;13484.528041518499;12120.878791211975;12434.067444941846;12581.93709031449;12749.49002039908;12777.872221277668;11916.523339066687;12247.081468890046;12554.811677824657;12565.371731413576;12685.556005539762;11851.081736719503;12203;12374.257544547356;12492.687682807638;12536.93731531373;10190.796184076426;10251.7437064074;10344.741381465223;10425.322354047266;10520.894791051991;9196.18324424234;9322.766930826512;9571.760705982406;9795.21638268951;10669.35983840972 14:49:05 INFO - PROCESS | 1656 | __end_tp_report 14:49:05 INFO - PROCESS | 1656 | __start_cc_report 14:49:05 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,372 14:49:05 INFO - PROCESS | 1656 | __end_cc_report 14:49:05 INFO - PROCESS | 1656 | __startTimestamp1476308945452__endTimestamp 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | ------- Summary: start ------- 14:49:05 INFO - PROCESS | 1656 | Number of tests: 6 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5685.52 Median:5825.21 stddev:653.67 (11.2%) stddev-sans-first:630.36 14:49:05 INFO - PROCESS | 1656 | Values: 3388.9 3390.7 3412.2 3415.8 3422.8 5567.8 5667.7 5700.2 5753.5 5776.0 4746.6 5569.8 5669.8 5700.8 5756.8 5178.7 5223.2 5248.8 5297.4 5299.2 5229.2 5307.6 5340.6 5349.0 5414.1 4141.7 4670.0 5326.1 5346.0 5360.1 5089.7 5108.9 5154.2 5195.1 5313.7 5083.7 5096.9 5142.7 5198.6 5232.1 5450.0 5578.3 5661.2 5712.5 5772.7 6112.1 6121.7 6122.9 6171.7 6192.2 6197.4 6235.2 6243.2 6244.2 6251.3 4819.4 5805.0 5809.0 5822.5 5823.7 6103.0 6169.1 6189.3 6213.8 6220.6 6164.0 6185.1 6191.8 6216.0 6224.8 6209.5 6253.1 6253.6 6259.0 6279.1 6024.7 6128.4 6131.0 6144.1 6241.7 5120.3 6194.6 6220.0 6228.4 6252.8 4937.7 4939.1 4967.8 4968.8 4981.3 3976.7 4338.2 4402.1 4408.3 4421.9 5782.9 5806.6 5825.2 5839.8 5840.9 6213.0 6219.2 6226.8 6268.7 6271.3 5635.4 5705.6 5723.9 5793.7 5817.6 5389.6 5415.8 5432.5 5464.8 5484.6 6311.1 6314.4 6327.5 6337.6 6352.6 5078.6 5154.9 5320.7 5801.4 6208.5 5372.5 5713.7 6137.5 6197.0 6247.9 6124.5 6232.0 6246.6 6257.0 6257.9 6103.6 6108.6 6118.2 6135.1 6230.7 5447.8 6116.7 6117.6 6142.8 6153.0 5887.4 5986.5 6189.6 6203.2 6234.6 5310.7 5852.1 5984.4 6037.4 6110.9 6148.2 6149.4 6184.6 6189.3 6200.1 5793.6 6046.8 6048.1 6052.5 6082.3 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:70675.46 Median:41244.38 stddev:152673.93 (370.2%) stddev-sans-first:153011.22 14:49:05 INFO - PROCESS | 1656 | Values: 468.5 472.0 478.6 510.5 514.8 40186.4 42075.6 42132.2 44236.7 44411.0 42669.6 43628.3 43683.1 43696.8 43717.6 9343.1 9620.2 9625.4 9649.4 9671.2 9476.1 9557.8 9564.9 9589.5 9592.3 9525.6 9602.6 9619.9 9629.7 9647.0 9446.1 9528.5 9547.4 9550.2 9577.0 9354.0 9391.6 9424.8 9492.9 9525.5 40837.6 41032.0 41168.6 41320.2 41597.6 3343.3 3365.2 3544.0 3684.2 3993.6 30849.7 31162.7 31307.4 31609.4 32207.9 3196.6 3218.9 3227.6 3279.9 3335.1 483688.0 483713.0 488381.0 488647.6 490456.0 61805.4 62099.0 62145.4 62229.0 62288.7 64430.0 64477.0 64576.0 64678.4 65007.3 14900.6 14951.3 15077.6 15157.8 15217.9 12705.7 12837.2 12840.2 12853.7 12885.7 17362.9 17475.0 17519.1 17620.7 17651.3 6999.7 7010.6 7015.2 7058.9 7146.7 708.1 839.5 921.4 1021.5 1028.4 23667.4 30250.8 30313.8 30360.7 30788.5 1004.6 1020.6 1055.9 1088.4 1134.7 13557.1 14041.0 15264.5 15765.8 15881.5 612803.0 618136.0 618303.0 622350.0 628628.0 57087.7 57090.1 57357.4 57404.0 57590.4 1290.7 1332.7 1336.5 1344.4 1350.6 23908.4 25900.4 29079.3 29212.9 29645.6 49378.0 49969.5 50275.2 50363.0 50459.0 595580.4 599075.0 600200.0 600486.0 601458.0 39790.0 39980.4 40234.6 40384.6 40810.6 46697.8 46972.0 47972.0 48135.5 48138.8 37901.8 37945.8 38058.6 39103.6 39678.4 36443.8 37572.1 38792.8 39092.6 39877.6 37414.0 37575.4 38220.2 38342.0 39037.0 33025.2 34242.0 34345.0 34684.8 35023.8 25271.9 25376.2 25705.0 25995.5 26006.6 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35882.58 Median:33653.99 stddev:101380.84 (301.2%) stddev-sans-first:101630.10 14:49:05 INFO - PROCESS | 1656 | Values: 331.4 359.7 366.0 371.3 378.2 18545.3 18836.7 20142.2 20188.6 20444.7 15280.8 19588.6 19603.5 19886.3 19895.3 5567.4 5754.3 5833.6 5873.5 5884.1 5420.3 5762.2 5813.9 5821.9 5822.7 5492.4 5755.9 5768.9 5787.5 5812.0 4689.3 4925.3 5181.4 5610.1 5770.1 4698.9 4792.6 5017.5 5021.2 5023.6 22465.6 22826.4 23043.3 23383.3 23415.8 2455.0 2590.8 2621.6 2739.7 3037.1 15125.2 15133.1 15280.2 15482.9 15985.7 1857.9 1912.3 1933.6 1948.1 2068.9 89731.1 108307.9 121492.0 136501.3 138849.0 19142.5 19658.0 19697.6 20139.2 20176.3 33409.5 33572.8 33617.0 33691.0 33884.8 9386.5 10191.8 10329.4 10628.5 10629.3 8720.9 8764.0 8829.6 8940.5 8959.5 7709.7 9287.3 9355.3 9470.0 9470.2 3813.4 3849.3 3858.9 3885.4 3901.8 700.4 787.6 808.2 832.8 848.6 18923.1 19050.4 20500.9 20934.7 21199.0 870.4 881.2 893.9 912.3 926.7 10555.9 10939.5 10984.8 10985.7 11058.9 596501.0 602276.0 609717.0 613820.0 624972.0 105271.4 122037.4 125362.7 127810.4 130114.3 958.5 992.2 1030.1 1030.9 1032.0 18859.8 18870.1 18956.1 19217.2 19388.0 39673.0 52026.5 52821.7 53368.7 54755.7 62653.6 72674.0 78028.0 80034.8 80997.0 6120.8 7644.0 7776.5 7852.9 7854.8 9399.4 11064.0 12197.8 12445.6 12481.1 8187.1 9094.8 10732.5 10950.1 11030.7 10355.5 10406.3 10495.8 10729.9 10731.1 8989.9 10844.0 10872.8 11035.4 11107.8 6793.4 7105.2 7163.2 7728.5 8077.4 10567.3 10654.1 10880.9 11064.8 11306.2 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5624.29 Median:5130.81 stddev:3130.32 (61.0%) stddev-sans-first:3122.43 14:49:05 INFO - PROCESS | 1656 | Values: 1325.6 1350.3 1623.9 1817.0 1858.7 7847.0 8067.6 8285.9 8881.4 8905.4 8320.3 8641.5 8659.7 8808.2 8851.7 2301.5 2314.8 2335.9 2338.3 2338.6 1971.3 2285.6 2295.7 2344.8 2362.5 2303.0 2303.5 2306.2 2330.8 2345.4 2289.1 2292.4 2294.5 2297.4 2303.6 2307.5 2310.7 2313.6 2314.8 2322.6 7297.3 8850.8 8919.4 8924.4 9219.7 5173.3 5247.8 5330.7 5338.9 5351.2 2989.9 3388.7 3443.4 3562.8 3578.9 930.6 1072.1 1073.3 1091.4 1099.1 9987.7 11493.4 11526.3 11579.7 11690.8 4553.8 4592.0 4786.2 4787.5 4791.2 8878.0 9451.9 9837.6 9856.3 9862.2 5293.1 5313.1 5316.2 5319.5 5333.8 4300.2 4432.8 4694.2 4813.3 4821.9 3806.9 3851.7 3929.3 3931.7 3988.6 1111.0 1273.7 1279.6 1280.6 1293.5 739.2 843.7 907.4 908.5 931.7 5679.5 5767.8 5874.0 5880.8 6095.7 869.6 946.9 947.6 958.8 991.9 3597.9 3906.4 3909.8 4023.2 4024.6 9221.6 10300.0 10740.2 10744.2 10786.0 8151.9 10494.0 10620.8 10711.4 10752.6 7566.6 7852.0 8072.4 8236.1 8246.2 5830.8 6086.7 6098.4 6141.3 6257.4 9632.5 9988.4 10029.6 10057.5 10073.2 9662.7 9795.6 10044.3 10136.8 10210.3 6792.2 7000.9 7171.5 7277.1 7524.7 8144.9 8320.9 8324.6 8349.3 8360.3 7430.7 7431.6 7432.1 7554.5 7706.7 7337.1 7387.7 7448.3 7511.5 7582.5 5930.3 7047.3 7074.9 7076.1 7094.6 4911.5 4953.5 5073.5 5092.4 5105.2 4775.9 4897.2 5001.3 5034.9 5156.4 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4437.99 Median:4851.92 stddev:1365.96 (28.2%) stddev-sans-first:1333.98 14:49:05 INFO - PROCESS | 1656 | Values: 298.0 299.4 307.6 314.4 315.1 4867.6 5394.8 5486.8 5612.3 5633.7 4940.9 5456.8 5460.4 5532.1 5568.3 3527.5 3717.7 4044.0 4243.4 4246.3 4019.3 4117.6 4208.3 4273.7 4318.6 3650.4 4017.7 4145.4 4156.3 4188.2 4170.6 4208.0 4209.5 4227.5 4235.0 3665.7 3675.5 3687.7 3693.3 3694.4 5142.4 5359.1 5370.5 5371.6 5387.5 4805.9 4806.8 4840.9 4841.9 4862.0 5525.6 5603.9 5645.0 5810.8 5836.4 3399.0 3963.8 3989.4 4002.6 4014.6 5433.5 5856.8 6101.5 6191.0 6270.6 4082.3 4096.4 4098.4 4098.5 4099.3 3367.1 3986.4 4012.2 4109.6 4164.3 4063.6 4779.2 4784.8 4811.4 4825.8 5900.8 5902.0 5936.6 5937.9 5957.7 1193.0 1213.8 1216.4 1286.8 1426.1 775.0 779.9 802.0 874.8 876.8 4276.9 4277.6 4394.2 4535.1 4536.0 4903.7 4949.6 5009.4 5024.0 5033.8 4093.4 4209.2 4309.3 4538.6 4567.5 2387.5 2473.9 2643.2 2654.9 2703.9 4389.9 4599.9 4890.5 4909.7 5041.9 4965.4 4997.4 5124.4 5161.0 5188.4 4906.8 5137.8 5147.5 5191.9 5279.4 4487.9 4617.7 4634.5 5001.9 5006.2 4359.6 4427.0 4721.9 4898.6 4946.3 4622.1 4713.7 4715.3 5250.9 5307.9 3052.3 3124.0 3225.4 3380.4 3565.0 4614.6 5024.5 5033.0 5066.7 5123.6 5148.7 5376.1 5755.6 5795.3 5854.3 5861.3 5869.5 5883.5 5892.7 5913.9 5547.3 5604.2 5622.3 5688.4 5764.3 5877.8 5880.3 5884.7 5903.5 5925.6 4884.8 4884.9 5503.5 5706.6 5713.0 14:49:05 INFO - PROCESS | 1656 | 14:49:05 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25843.44 Median:3230.17 stddev:42725.64 (1322.7%) stddev-sans-first:42803.65 14:49:05 INFO - PROCESS | 1656 | Values: 655.9 686.8 698.8 708.5 734.9 14828.8 15204.4 15503.1 15756.6 15952.6 14181.7 14187.6 14191.2 14346.0 14423.6 816.4 835.8 837.1 837.5 839.4 664.5 681.4 815.5 832.1 849.1 845.5 882.4 887.3 887.5 888.4 844.5 879.0 880.2 880.9 882.5 625.8 626.1 647.3 650.2 656.6 14512.3 14535.9 14797.9 14814.9 14872.9 11439.9 14077.0 14213.1 14303.3 14546.9 11585.9 11631.1 11743.7 11830.6 12079.8 3774.2 3780.8 3805.8 3820.7 3946.8 115747.0 116869.0 118041.0 118904.0 121372.0 10087.8 10374.6 10596.2 11029.4 11162.0 16190.9 17212.0 17477.7 18752.5 20023.7 7565.1 7611.1 7688.8 7813.1 7861.1 4282.4 4296.3 4329.3 4349.1 4353.4 3023.4 3436.9 3520.0 3527.6 3533.6 659.2 659.4 661.4 711.4 725.7 568.7 570.5 609.6 663.1 670.8 9384.7 9649.6 9677.8 9683.4 9733.4 618.6 642.1 646.0 678.9 689.6 4600.0 4663.4 4701.4 4806.4 4857.6 103410.0 128108.0 161986.0 170099.0 173886.0 119293.0 138052.0 140685.0 146028.0 147101.0 123300.8 124396.4 124692.4 125005.1 128022.4 7276.5 8498.5 8929.7 8949.2 9097.3 66792.7 68671.7 69223.7 69404.0 69480.0 97885.0 103353.0 104147.0 104247.0 106025.0 10656.7 11829.8 11870.1 11953.8 12120.3 12882.3 13191.7 13229.3 13464.5 13484.5 12120.9 12434.1 12581.9 12749.5 12777.9 11916.5 12247.1 12554.8 12565.4 12685.6 11851.1 12203.0 12374.3 12492.7 12536.9 10190.8 10251.7 10344.7 10425.3 10520.9 9196.2 9322.8 9571.8 9795.2 10669.4 14:49:05 INFO - PROCESS | 1656 | -------- Summary: end -------- 14:49:05 INFO - PROCESS | 1656 | 14:49:06 INFO - TEST-INFO | 1656: exit 0 14:49:06 INFO - TEST-OK | dromaeo_css | took 1115633ms 14:49:06 INFO - TEST-START | kraken 14:49:06 INFO - operating with platform_type : mac_ 14:49:06 INFO - Initialising browser for kraken test... 14:49:06 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpLA1A_M/profile http://localhost:49268/getInfo.html) 14:49:08 INFO - PROCESS | 1664 | 2016-10-12 14:49:08.733 plugin-container[1665:9976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 14:49:08 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:08 INFO - PROCESS | 1664 | 2016-10-12 14:49:08.737 plugin-container[1665:9976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 14:49:08 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:08 INFO - PROCESS | 1664 | 2016-10-12 14:49:08.737 plugin-container[1665:9976] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:49:08 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 14:49:08 INFO - PROCESS | 1664 | colorDepth:24 14:49:08 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 14:49:08 INFO - PROCESS | 1664 | __metrics 14:49:08 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 14:49:09 INFO - TEST-INFO | 1664: exit 0 14:49:09 INFO - Browser initialized. 14:49:09 INFO - Running cycle 1/1 for kraken test... 14:49:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpLA1A_M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:11 INFO - PROCESS | 1666 | 2016-10-12 14:49:11.917 plugin-container[1667:10088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 14:49:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:11 INFO - PROCESS | 1666 | 2016-10-12 14:49:11.917 plugin-container[1667:10088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 14:49:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:11 INFO - PROCESS | 1666 | 2016-10-12 14:49:11.917 plugin-container[1667:10088] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:49:21 INFO - PROCESS | 1666 | RSS: Main: 239063040 14:49:21 INFO - PROCESS | 1666 | 14:49:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:25 INFO - PROCESS | 1666 | RSS: Main: 252698624 14:49:25 INFO - PROCESS | 1666 | 14:49:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 14:49:28 INFO - PROCESS | 1666 | RSS: Main: 261025792 14:49:28 INFO - PROCESS | 1666 | 14:49:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 14:49:32 INFO - PROCESS | 1666 | RSS: Main: 261361664 14:49:32 INFO - PROCESS | 1666 | 14:49:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 14:49:35 INFO - PROCESS | 1666 | RSS: Main: 262258688 14:49:35 INFO - PROCESS | 1666 | 14:49:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:37 INFO - PROCESS | 1666 | RSS: Main: 262557696 14:49:37 INFO - PROCESS | 1666 | 14:49:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:41 INFO - PROCESS | 1666 | RSS: Main: 263610368 14:49:41 INFO - PROCESS | 1666 | 14:49:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 14:49:44 INFO - PROCESS | 1666 | RSS: Main: 263389184 14:49:44 INFO - PROCESS | 1666 | 14:49:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 14:49:48 INFO - PROCESS | 1666 | RSS: Main: 265105408 14:49:48 INFO - PROCESS | 1666 | 14:49:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:50 INFO - PROCESS | 1666 | RSS: Main: 264851456 14:49:50 INFO - PROCESS | 1666 | 14:49:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:49:53 INFO - PROCESS | 1666 | RSS: Main: 264978432 14:49:53 INFO - PROCESS | 1666 | 14:49:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:49:56 INFO - PROCESS | 1666 | RSS: Main: 264896512 14:49:56 INFO - PROCESS | 1666 | 14:49:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:00 INFO - PROCESS | 1666 | RSS: Main: 265474048 14:50:00 INFO - PROCESS | 1666 | 14:50:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:03 INFO - PROCESS | 1666 | RSS: Main: 265293824 14:50:03 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 14:50:05 INFO - PROCESS | 1666 | RSS: Main: 266670080 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | __start_tp_report 14:50:05 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 14:50:05 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 14:50:05 INFO - PROCESS | 1666 | |i|pagename|runs| 14:50:05 INFO - PROCESS | 1666 | |0;ai-astar;102;147;119;118;118;115;123;150;116;118 14:50:05 INFO - PROCESS | 1666 | |1;audio-beat-detection;173;143;116;155;116;116;117;113;147;115 14:50:05 INFO - PROCESS | 1666 | |2;audio-dft;200;210;238;283;196;195;198;195;194;193 14:50:05 INFO - PROCESS | 1666 | |3;audio-fft;82;60;60;86;65;59;60;59;78;59 14:50:05 INFO - PROCESS | 1666 | |4;audio-oscillator;87;71;87;72;69;71;90;69;74;89 14:50:05 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;90;80;133;88;90;87;92;88;81;126 14:50:05 INFO - PROCESS | 1666 | |6;imaging-darkroom;95;97;95;97;95;95;134;97;96;97 14:50:05 INFO - PROCESS | 1666 | |7;imaging-desaturate;91;91;97;76;149;147;95;105;77;147 14:50:05 INFO - PROCESS | 1666 | |8;json-parse-financial;59;56;55;58;57;57;57;58;58;56 14:50:05 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;74;69;69;84;79;76;71;70;70;70 14:50:05 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;87;92;90;97;105;84;88;89;89;86 14:50:05 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;180;160;153;175;173;145;153;158;195;247 14:50:05 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;173;177;175;173;200;179;172;194;229;171 14:50:05 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;69;69;69;72;69;69;71;73;71 14:50:05 INFO - PROCESS | 1666 | __end_tp_report 14:50:05 INFO - PROCESS | 1666 | __start_cc_report 14:50:05 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,973 14:50:05 INFO - PROCESS | 1666 | __end_cc_report 14:50:05 INFO - PROCESS | 1666 | __startTimestamp1476309005625__endTimestamp 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | ------- Summary: start ------- 14:50:05 INFO - PROCESS | 1666 | Number of tests: 14 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:122.60 Median:118.50 stddev:14.71 (12.4%) stddev-sans-first:13.59 14:50:05 INFO - PROCESS | 1666 | Values: 102.0 147.0 119.0 118.0 118.0 115.0 123.0 150.0 116.0 118.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:130.00 stddev:21.58 (16.6%) stddev-sans-first:16.73 14:50:05 INFO - PROCESS | 1666 | Values: 173.0 143.0 116.0 155.0 116.0 116.0 117.0 113.0 147.0 115.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:210.20 Median:199.00 stddev:28.96 (14.6%) stddev-sans-first:30.48 14:50:05 INFO - PROCESS | 1666 | Values: 200.0 210.0 238.0 283.0 196.0 195.0 198.0 195.0 194.0 193.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:10.80 (17.3%) stddev-sans-first:9.96 14:50:05 INFO - PROCESS | 1666 | Values: 82.0 60.0 60.0 86.0 65.0 59.0 60.0 59.0 78.0 59.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.50 stddev:9.06 (11.3%) stddev-sans-first:8.99 14:50:05 INFO - PROCESS | 1666 | Values: 87.0 71.0 87.0 72.0 69.0 71.0 90.0 69.0 74.0 89.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:88.00 stddev:18.39 (20.9%) stddev-sans-first:19.40 14:50:05 INFO - PROCESS | 1666 | Values: 90.0 80.0 133.0 88.0 90.0 87.0 92.0 88.0 81.0 126.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:96.50 stddev:12.05 (12.5%) stddev-sans-first:12.66 14:50:05 INFO - PROCESS | 1666 | Values: 95.0 97.0 95.0 97.0 95.0 95.0 134.0 97.0 96.0 97.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:84.00 stddev:29.03 (34.6%) stddev-sans-first:30.17 14:50:05 INFO - PROCESS | 1666 | Values: 91.0 91.0 97.0 76.0 149.0 147.0 95.0 105.0 77.0 147.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:1.20 (2.1%) stddev-sans-first:1.05 14:50:05 INFO - PROCESS | 1666 | Values: 59.0 56.0 55.0 58.0 57.0 57.0 57.0 58.0 58.0 56.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:72.50 stddev:5.05 (7.0%) stddev-sans-first:5.35 14:50:05 INFO - PROCESS | 1666 | Values: 74.0 69.0 69.0 84.0 79.0 76.0 71.0 70.0 70.0 70.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:89.00 stddev:6.15 (6.9%) stddev-sans-first:6.37 14:50:05 INFO - PROCESS | 1666 | Values: 87.0 92.0 90.0 97.0 105.0 84.0 88.0 89.0 89.0 86.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:173.90 Median:174.00 stddev:29.78 (17.1%) stddev-sans-first:31.51 14:50:05 INFO - PROCESS | 1666 | Values: 180.0 160.0 153.0 175.0 173.0 145.0 153.0 158.0 195.0 247.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:178.00 stddev:18.53 (10.4%) stddev-sans-first:19.20 14:50:05 INFO - PROCESS | 1666 | Values: 173.0 177.0 175.0 173.0 200.0 179.0 172.0 194.0 229.0 171.0 14:50:05 INFO - PROCESS | 1666 | 14:50:05 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:1.79 (2.6%) stddev-sans-first:1.56 14:50:05 INFO - PROCESS | 1666 | Values: 67.0 69.0 69.0 69.0 72.0 69.0 69.0 71.0 73.0 71.0 14:50:05 INFO - PROCESS | 1666 | -------- Summary: end -------- 14:50:05 INFO - PROCESS | 1666 | 14:50:06 INFO - TEST-INFO | 1666: exit 0 14:50:06 INFO - TEST-OK | kraken | took 60394ms 14:50:06 INFO - SUITE-END | took 1176s 14:50:06 INFO - Completed test suite (00:19:36) 14:50:06 INFO - javascript benchmark 14:50:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7366.773394691646, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.949165762514, 3390.6948374646286, 3412.2322477442563, 3415.8292085395733, 3422.7775194612364, 5567.777288908449, 5667.688277144755, 5700.173474846145, 5753.539716822655, 5775.971120144399, 4746.596539294164, 5569.805056823012, 5669.773209071643, 5700.7719691212405, 5756.8272951811405, 5178.715170665612, 5223.190405487146, 5248.816291429801, 5297.364316282033, 5299.178627312773, 5229.163333866582, 5307.575393968474, 5340.599455040868, 5349.0, 5414.10667239905, 4141.689373296999, 4670.019295947855, 5326.067938110815, 5346.0, 5360.061989151903, 5089.720065396402, 5108.923366149511, 5154.201098829687, 5195.116830138885, 5313.734313284321, 5083.74581270935, 5096.949030509684, 5142.717150556718, 5198.558122559549, 5232.136697444917, 5449.972750136264, 5578.30271216098, 5661.2357331760095, 5712.485876271156, 5772.7113644317615, 6112.07796728157, 6121.693915304217, 6122.886104953791, 6171.660558669271, 6192.225971753531, 6197.411245931629, 6235.158253635792, 6243.1883855099, 6244.219472565929, 6251.28110267316, 4819.373481447425, 5805.0, 5808.970955145197, 5822.534197264209, 5823.737931793079, 6103.004334463626, 6169.074638804215, 6189.319174890758, 6213.813585592439, 6220.595661282002, 6164.044573091178, 6185.103160041859, 6191.84520386994, 6216.036514340284, 6224.751009959551, 6209.5032397408095, 6253.093301471261, 6253.624782512973, 6259.0, 6279.058141278845, 6024.728887200086, 6128.3565225651555, 6131.040261761288, 6144.064619644798, 6241.687915604201, 5120.283160357553, 6194.566380353332, 6220.0, 6228.439440450381, 6252.812415627538, 4937.679050861682, 4939.1109600271575, 4967.751612419364, 4968.826091086795, 4981.252812078144, 3976.741511801724, 4338.175746608197, 4402.097569998143, 4408.272635015187, 4421.911561768747, 5782.88434231313, 5806.564507661942, 5825.2135961644635, 5839.824805255849, 5840.941590584125, 6213.0, 6219.191505104308, 6226.782062627786, 6268.655223962726, 6271.27880293763, 5635.380108188096, 5705.62913410627, 5723.914141287884, 5793.652380857162, 5817.621854579439, 5389.649672771257, 5415.83752487426, 5432.456754324536, 5464.808731694451, 5484.643498172606, 6311.084892690534, 6314.400131987454, 6327.493800496041, 6337.556371053982, 6352.618842869535, 5078.644494885322, 5154.917467331902, 5320.707361095177, 5801.419858014165, 6208.4722798562625, 5372.516473517402, 5713.7428815704225, 6137.478314343152, 6197.0, 6247.875042499216, 6124.533510047256, 6232.0, 6246.593971391845, 6256.968715156395, 6257.906131407942, 6103.6407333109855, 6108.645631960618, 6118.162361256178, 6135.07973803933, 6230.719617617127, 5447.754851031634, 6116.676957396471, 6117.614269788275, 6142.815715528542, 6152.969235153795, 5887.381824908494, 5986.491148252448, 6189.597676151036, 6203.2245969253845, 6234.594751341148, 5310.707911064774, 5852.063669812809, 5984.431479009487, 6037.426444487766, 6110.877782444415, 6148.200733904564, 6149.385061493815, 6184.5980011299125, 6189.288231853395, 6200.131981522681, 5793.623414478214, 6046.758129674764, 6048.092786082123, 6052.455279024909, 6082.300535438304], "value": 5645.553584588816, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.46126954002915, 471.98348057817975, 478.5931957835838, 510.50991048593346, 514.8018013064969, 40186.397204041925, 42075.57924420751, 42132.157356852884, 44236.67289981306, 44411.0, 42669.573304266945, 43628.345574816405, 43683.12633747331, 43696.78151609238, 43717.562824371744, 9343.112404321595, 9620.230381569492, 9625.37435066722, 9649.421034737903, 9671.177949874234, 9476.099770521789, 9557.808843823119, 9564.856527152098, 9589.472579008152, 9592.280578956572, 9525.618975240983, 9602.615895364177, 9619.855702164474, 9629.662961796339, 9646.951765241163, 9446.055394460567, 9528.475933823636, 9547.379420337656, 9550.188234000083, 9576.952115239379, 9354.0, 9391.62433502659, 9424.811503769886, 9492.857607135898, 9525.523723813782, 40837.591624083674, 41032.0, 41168.58831411708, 41320.1735965279, 41597.584024159674, 3343.264481813997, 3365.1923538350866, 3544.043108360754, 3684.207895302523, 3993.6405723484736, 30849.691503084905, 31162.688373116205, 31307.373852522822, 31609.367812643617, 32207.87272445453, 3196.5844440222854, 3218.903432897017, 3227.6126864776143, 3279.8688052477873, 3335.0995231287416, 483688.0, 483713.0, 488381.0, 488647.55676221394, 490456.0, 61805.38194618086, 62099.0, 62145.378546214866, 62229.0, 62288.688556556925, 64430.0, 64477.0, 64576.0, 64678.353216467236, 65007.349926501076, 14900.62748431298, 14951.252437378087, 15077.623059423382, 15157.772633410508, 15217.934744567774, 12705.745885082246, 12837.229766214057, 12840.165389249669, 12853.742925141445, 12885.677858053623, 17362.913185434245, 17475.038872862115, 17519.124043797758, 17620.735688964676, 17651.32312430317, 6999.685014174374, 7010.614416207158, 7015.228324884259, 7058.9294107059295, 7146.678399472036, 708.0618180910315, 839.5214727605206, 921.428714197202, 1021.4626986385442, 1028.4292217819032, 23667.408314791923, 30250.84874575657, 30313.848430757706, 30360.696393035785, 30788.538171927437, 1004.633308842282, 1020.550957578663, 1055.883852776194, 1088.4013792414141, 1134.6652737442473, 13557.051006429456, 14041.017128800886, 15264.542063738105, 15765.763513547307, 15881.538361553747, 612803.0, 618136.0, 618303.0, 622350.0, 628628.0, 57087.714561427754, 57090.14364784532, 57357.42642573604, 57404.0, 57590.42409575934, 1290.6708789258678, 1332.6934804994905, 1336.4520546575857, 1344.3748656874404, 1350.5678182981549, 23908.372953510006, 25900.35249118787, 29079.273018174714, 29212.853935731036, 29645.555316669837, 49378.0, 49969.50030499649, 50275.24587131123, 50363.0, 50459.0, 595580.4419558183, 599075.0, 600199.9990000022, 600486.0, 601458.0, 39790.0, 39980.400293996194, 40234.597654024255, 40384.59615403808, 40810.59189408187, 46697.83255418642, 46972.0, 47972.040559187924, 48135.518644813106, 48138.75930620325, 37901.81049094737, 37945.81027094847, 38058.602225451716, 39103.60896391114, 39678.404823927085, 36443.81778091199, 37572.060698482754, 38792.80603596964, 39092.56834503847, 39877.601223988546, 37414.0, 37575.43636845395, 38220.235595287384, 38342.041448964, 39037.0, 33025.174370640925, 34242.0, 34345.0, 34684.82657586696, 35023.824880876455, 25271.87364063168, 25376.238712837905, 25704.971801128493, 25995.480090398465, 26006.60990085188], "value": 20071.523105638713, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.3968577189515, 359.68707224714484, 365.97255205859557, 371.2963933346308, 378.2133163020917, 18545.258189672415, 18836.717449238255, 20142.19431222753, 20188.59622807543, 20444.693329600068, 15280.84719152811, 19588.608227835433, 19603.5099122522, 19886.303979360724, 19895.30366437175, 5567.4432556744305, 5754.280714910637, 5833.620814647055, 5873.530117590604, 5884.117382392654, 5420.268263784398, 5762.164486149527, 5813.854653633651, 5821.854453638651, 5822.7088645567765, 5492.395836458007, 5755.856103597423, 5768.913466297988, 5787.450192231731, 5812.041013232812, 4689.273162659793, 4925.31045653607, 5181.378234611833, 5610.074337734269, 5770.105633626795, 4698.906021879578, 4792.640551958617, 5017.54842064216, 5021.221710634857, 5023.648344615878, 22465.55068898629, 22826.42933926665, 23043.30870073884, 23383.298501044996, 23415.76584234153, 2454.950900981988, 2590.8315959462575, 2621.554335762925, 2739.671239451279, 3037.1040543039635, 15125.243737813065, 15133.092014479167, 15280.235988200657, 15482.922585387001, 15985.680286394323, 1857.8606604504716, 1912.3020097664391, 1933.6132773445227, 1948.074664534349, 2068.917243310266, 89731.10268897293, 108307.91692083135, 121492.0, 136501.31749341288, 138849.0, 19142.521436963907, 19658.0, 19697.606047878962, 20139.19443222284, 20176.29382971589, 33409.49885751716, 33572.83213583916, 33616.99149025533, 33690.98927032193, 33884.83057584745, 9386.530673466299, 10191.847122293171, 10329.431881246453, 10628.521716522775, 10629.25595208328, 8720.8691869622, 8763.956180219058, 8829.646814127364, 8940.46357218569, 8959.462432254086, 7709.7301594443925, 9287.349885508085, 9355.29835262358, 9470.0, 9470.242380609534, 3813.370793819044, 3849.3071247175217, 3858.9421158682644, 3885.4171874218528, 3901.843926242975, 700.3556727810427, 787.5943888897223, 808.2442915873677, 832.8042909916165, 848.5841937450605, 18923.05384730758, 19050.428487145407, 20500.89749551243, 20934.685979710317, 21199.046042928105, 870.425519157353, 881.1805021330224, 893.8659201119754, 912.3020889019905, 926.749777560055, 10555.94722026385, 10939.453027348602, 10984.835227471594, 10985.725356866142, 11058.944705276423, 596501.0, 602276.0, 609717.0, 613820.0, 624972.0, 105271.36821579521, 122037.38981305214, 125362.74637253693, 127810.36094819652, 130114.34942825415, 958.5399008475971, 992.1814503035025, 1030.0986636692894, 1030.907218350352, 1031.9793604127876, 18859.811401886356, 18870.056497174814, 18956.0521973898, 19217.231310747415, 19388.030598469737, 39673.0, 52026.47973520368, 52821.7358913203, 53368.73315633397, 54755.72622136864, 62653.554054525615, 72674.0, 78028.0, 80034.79947800671, 80997.0, 6120.816375508742, 7643.961780191064, 7776.494527855672, 7852.882206766789, 7854.764357069297, 9399.436033837992, 11064.0, 12197.75604487923, 12445.635329287406, 12481.126321157431, 8187.1403502631365, 9094.772630684285, 10732.51703673321, 10950.123990080934, 11030.724231893946, 10355.534000970125, 10406.323588966692, 10495.842562361724, 10729.9463502682, 10731.087857532197, 8989.865152022856, 10844.0, 10872.782544348911, 11035.44822758875, 11107.777844443228, 6793.354631310018, 7105.18290396611, 7163.2478589747325, 7728.497647652885, 8077.434579579373, 10567.260291779809, 10654.094401975919, 10880.945595271973, 11064.778704425706, 11306.208565400342], "value": 10191.768112202828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.5956933135392, 1350.2776014832064, 1623.8863279570428, 1816.9636607267855, 1858.702607582787, 7846.960765196166, 8067.556284404355, 8285.875711864319, 8881.378303518757, 8905.4211476254, 8320.251177394033, 8641.52471614061, 8659.69691060813, 8808.207261346477, 8851.690190843306, 2301.4591570980765, 2314.849534780234, 2335.9182428615004, 2338.2634470141934, 2338.6141286687675, 1971.300188433107, 2285.6228722260807, 2295.6900818389554, 2344.8475849069755, 2362.492064206195, 2302.9654555181687, 2303.527776805759, 2306.1582522379376, 2330.848494847838, 2345.355027367479, 2289.061484791236, 2292.3695983604475, 2294.4952110535655, 2297.3911913342977, 2303.57383883982, 2307.48081681622, 2310.722713274418, 2313.6298192289323, 2314.780095890888, 2322.6283794592955, 7297.343239108506, 8850.822983540358, 8919.375643704943, 8924.375293729441, 9219.67731129414, 5173.275741396207, 5247.845473995715, 5330.653507521999, 5338.932213557266, 5351.170568561879, 2989.880404783806, 3388.745844061705, 3443.4490481522835, 3562.8218589070443, 3578.910527236814, 930.5579849571467, 1072.1101485766874, 1073.3398959639844, 1091.3888222595292, 1099.115212254135, 9987.700368988943, 11493.425328733614, 11526.308421494738, 11579.652610421701, 11690.824637630527, 4553.817847286137, 4591.95408045922, 4786.210275304606, 4787.521247875255, 4791.185498465269, 8877.955610222038, 9451.865776106773, 9837.557309920927, 9856.310058295994, 9862.211023118134, 5293.126634105406, 5313.149896016619, 5316.202569614588, 5319.521243088141, 5333.759980800874, 4300.247456695066, 4432.84485043022, 4694.201985662446, 4813.302071199656, 4821.9035619288115, 3806.885793426202, 3851.7111216658864, 3929.2730844793805, 3931.744436611611, 3988.564116917907, 1110.9833352499718, 1273.6624794429483, 1279.6289076167807, 1280.6030130659526, 1293.5278623302427, 739.1979702023258, 843.7131375332293, 907.4237858959608, 908.5230254116642, 931.743770463128, 5679.545636349082, 5767.798127065533, 5873.970630146822, 5880.823835232971, 6095.725692343855, 869.573908784691, 946.9005754395827, 947.6495993209786, 958.769895225141, 991.8710567626163, 3597.8740744074357, 3906.43356713275, 3909.843606255775, 4023.1752490739327, 4024.5774193709826, 9221.631134754669, 10300.0, 10740.248182627298, 10744.247902646895, 10786.0, 8151.918480815235, 10494.003069708366, 10620.787584248272, 10711.410872401937, 10752.569897204025, 7566.583837888859, 7851.960740196148, 8072.4349295548745, 8236.094029656733, 8246.175382461826, 5830.82507524775, 6086.744080531742, 6098.390160983866, 6141.324454310112, 6257.43683068526, 9632.518374081128, 9988.400695958266, 10029.598816047277, 10057.547410366406, 10073.19414446857, 9662.71011869617, 9795.559199835881, 10044.347117437635, 10136.847947280945, 10210.336328138646, 6792.218894827158, 7000.859982800417, 7171.533850299714, 7277.090363704537, 7524.699012039458, 8144.877826832484, 8320.875186872081, 8324.62539185726, 8349.33205343559, 8360.331173506227, 7430.7027718892805, 7431.554106753612, 7432.070991126109, 7554.471187016856, 7706.653200606098, 7337.0828646419195, 7387.741429050173, 7448.255174482725, 7511.474196806171, 7582.54504729718, 5930.258717660293, 7047.260037695968, 7074.893876591752, 7076.0801095857205, 7094.6097964612445, 4911.533404326583, 4953.504649535018, 5073.492650734897, 5092.363454568179, 5105.2086926526445, 4775.928361074517, 4897.240927656219, 5001.349824522788, 5034.874128146826, 5156.4070131935305], "value": 4524.36724786261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.98510074496284, 299.4265980647061, 307.60780005493, 314.4465740297076, 315.0611178687511, 4867.634927380443, 5394.838154855351, 5486.835394938148, 5612.270404847369, 5633.746481408336, 4940.876478088049, 5456.7544460499275, 5460.389185190368, 5532.087205611079, 5568.303962004749, 3527.5061491391225, 3717.6654101130894, 4043.9595604043875, 4243.408721729336, 4246.299360605496, 4019.276530224573, 4117.64705882353, 4208.347706105558, 4273.722208056482, 4318.632916202127, 3650.4159334506485, 4017.718759686823, 4145.419641250227, 4156.314208155666, 4188.246115699173, 4170.624643782059, 4208.0, 4209.473815773028, 4227.513835908849, 4234.957650423487, 3665.6700896919406, 3675.5405574303213, 3687.6681098701247, 3693.316736403774, 3694.4458331250257, 5142.357205349332, 5359.124306492736, 5370.516653501165, 5371.597130215249, 5387.4612538746205, 4805.903881922342, 4806.831760888352, 4840.878978025577, 4841.8547443576745, 4861.975690121527, 5525.6462166769, 5603.851210501878, 5645.0, 5810.796622118206, 5836.394991377363, 3399.0, 3963.7819919904396, 3989.404238304672, 4002.5597184309704, 4014.598540145962, 5433.527744876072, 5856.799356131983, 6101.450869421773, 6190.966426451052, 6270.623762574261, 4082.3264161413035, 4096.365063415175, 4098.405731168964, 4098.508179018537, 4099.323611604052, 3367.0572239772905, 3986.3855138668887, 4012.1775036117524, 4109.630133288018, 4164.292070348048, 4063.593640635913, 4779.187538118528, 4784.827717209306, 4811.446683631357, 4825.793551612097, 5900.763969441175, 5901.970490147522, 5936.614120082268, 5937.9406205937385, 5957.702114894238, 1192.9642110736693, 1213.7754515414676, 1216.3819339976192, 1286.8327117474669, 1426.073052515873, 774.9728759493504, 779.8908152858604, 801.9558924259105, 874.8119154381839, 876.7983363826228, 4276.850310239127, 4277.556566963562, 4394.209042372339, 4535.074876704573, 4536.0, 4903.681260718042, 4949.604031677457, 5009.423916249678, 5024.010713625387, 5033.7734801934, 4093.4269202311702, 4209.221294060609, 4309.310510318397, 4538.568835960578, 4567.520410356931, 2387.4747555537756, 2473.9010439582216, 2643.220250026247, 2654.9380247900995, 2703.864806759654, 4389.868303950887, 4599.885002874888, 4890.462049174588, 4909.705417674951, 5041.873953151201, 4965.37122940878, 4997.400311962588, 5124.410692770304, 5161.019406312788, 5188.379868166628, 4906.754662266872, 5137.794488220429, 5147.485251474973, 5191.9480805192225, 5279.41926388097, 4487.865364039084, 4617.722936623813, 4634.513376095596, 5001.924971125363, 5006.246564389498, 4359.585839345257, 4427.0, 4721.9055618888115, 4898.559129678346, 4946.307516947558, 4622.104936975824, 4713.717176969393, 4715.288708992412, 5250.868728281824, 5307.893842123213, 3052.2827135623556, 3124.0, 3225.371187550272, 3380.4253276942486, 3564.964350356567, 4614.561616646413, 5024.497550244946, 5033.043721692867, 5066.746662666926, 5123.590112791042, 5148.665336753099, 5376.139817629317, 5755.625884317674, 5795.33353664334, 5854.268216472941, 5861.32594751609, 5869.53043756507, 5883.529317654663, 5892.705364731832, 5913.852153696192, 5547.306586676666, 5604.218805863516, 5622.2731974991275, 5688.431156884279, 5764.279465066867, 5877.764889404376, 5880.294364676266, 5884.735186916513, 5903.527717782481, 5925.555583331181, 4884.804607815648, 4884.926726099183, 5503.532199763065, 5706.572007099568, 5712.971435142797], "value": 3996.8537469200655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.9016147577864, 686.8214264291285, 698.7519430602136, 708.5217478202212, 734.9154847192574, 14828.77756833647, 15204.391824327015, 15503.069815811032, 15756.60608484787, 15952.601184970381, 14181.71636567268, 14187.574372768808, 14191.219482928434, 14346.0, 14423.567292981228, 816.4366587054928, 835.8244768598571, 837.0666706622134, 837.5016864965336, 839.4166054592062, 664.4717449627558, 681.41057984843, 815.5310696349593, 832.134580036761, 849.1381248033221, 845.5222799118509, 882.4087861133056, 887.3344991256557, 887.5384799904047, 888.3736965439382, 844.4595458906305, 878.964841406343, 880.2473884828507, 880.9207171354577, 882.5146169606691, 625.7872323410016, 626.1203009771278, 647.3429469088878, 650.1742786660961, 656.5633853487425, 14512.346944387422, 14535.927320363437, 14797.852021479863, 14814.851851481455, 14872.851271487254, 11439.88560114405, 14077.0, 14213.076150050318, 14303.284835758275, 14546.854531454655, 11585.942070289679, 11631.127665425127, 11743.7064073398, 11830.645080647595, 12079.818802717966, 3774.1885494618514, 3780.8298626561873, 3805.809709514541, 3820.6943444524372, 3946.8026598669953, 115747.0, 116869.0, 118041.0, 118904.0, 121372.0, 10087.848682269772, 10374.585016599402, 10596.152307815357, 11029.44852757359, 11162.0, 16190.919045404933, 17212.0, 17477.65044699099, 18752.531186720167, 20023.696090145255, 7565.130010048805, 7611.086669599684, 7688.8462230756195, 7813.140554538996, 7861.095973962953, 4282.421873047098, 4296.2696341621995, 4329.328954012133, 4349.108432771274, 4353.412289340962, 3023.410434965205, 3436.9312613747584, 3519.964800352015, 3527.6472352764517, 3533.5936367318086, 659.152988409891, 659.4361820643377, 661.394823736276, 711.4450728431832, 725.6661935509619, 568.6730130175166, 570.5264630356794, 609.6464050850585, 663.1048085085193, 670.792054463118, 9384.67153649619, 9649.614015439303, 9677.758056048515, 9683.426443202958, 9733.350585752796, 618.5793660311032, 642.1390077116185, 645.9806205813833, 678.91852977643, 689.6448329110513, 4599.977000114978, 4663.391130060124, 4701.435827700698, 4806.447258565309, 4857.635677324215, 103410.0, 128108.0, 161986.0, 170099.0, 173886.0, 119293.0, 138052.0, 140685.0, 146028.0, 147100.99999999785, 123300.76699233073, 124396.37801810933, 124692.37653811673, 125005.12492312622, 128022.35988820183, 7276.454265930182, 8498.532580707873, 8929.73210803677, 8949.194572488508, 9097.317701172571, 66792.6660366695, 68671.65664171647, 69223.65388173026, 69404.0, 69480.0, 97885.0, 103352.96647033734, 104147.0, 104247.0, 106025.0, 10656.680299591024, 11829.76340473203, 11870.109741769229, 11953.820692689442, 12120.333381664093, 12882.291473968648, 13191.67020824487, 13229.272390018641, 13464.461421543421, 13484.528041518499, 12120.878791211975, 12434.067444941846, 12581.93709031449, 12749.49002039908, 12777.872221277668, 11916.523339066687, 12247.081468890046, 12554.811677824657, 12565.371731413576, 12685.556005539762, 11851.081736719503, 12203.0, 12374.257544547356, 12492.687682807638, 12536.93731531373, 10190.796184076426, 10251.7437064074, 10344.741381465223, 10425.322354047266, 10520.894791051991, 9196.18324424234, 9322.766930826512, 9571.760705982406, 9795.21638268951, 10669.35983840972], "value": 7652.485515377443, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 147.0, 119.0, 118.0, 118.0, 115.0, 123.0, 150.0, 116.0, 118.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 143.0, 116.0, 155.0, 116.0, 116.0, 117.0, 113.0, 147.0, 115.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 210.0, 238.0, 283.0, 196.0, 195.0, 198.0, 195.0, 194.0, 193.0], "value": 210.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 86.0, 65.0, 59.0, 60.0, 59.0, 78.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 87.0, 72.0, 69.0, 71.0, 90.0, 69.0, 74.0, 89.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 80.0, 133.0, 88.0, 90.0, 87.0, 92.0, 88.0, 81.0, 126.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 97.0, 95.0, 95.0, 134.0, 97.0, 96.0, 97.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 97.0, 76.0, 149.0, 147.0, 95.0, 105.0, 77.0, 147.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 55.0, 58.0, 57.0, 57.0, 57.0, 58.0, 58.0, 56.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 69.0, 84.0, 79.0, 76.0, 71.0, 70.0, 70.0, 70.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 90.0, 97.0, 105.0, 84.0, 88.0, 89.0, 89.0, 86.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 160.0, 153.0, 175.0, 173.0, 145.0, 153.0, 158.0, 195.0, 247.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 177.0, 175.0, 173.0, 200.0, 179.0, 172.0, 194.0, 229.0, 171.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 69.0, 72.0, 69.0, 69.0, 71.0, 73.0, 71.0], "value": 69.9, "unit": "score"}]}]} 14:50:06 INFO - Return code: 0 14:50:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:06 ERROR - # TBPL SUCCESS # 14:50:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:50:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:50:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:50:06 INFO - 2016-10-12 14:50:06.983 screenresolution[1671:10480] starting screenresolution argv=screenresolution get 14:50:06 INFO - 2016-10-12 14:50:06.986 screenresolution[1671:10480] Display 0: 1600x1200x32@60 14:50:06 INFO - 2016-10-12 14:50:06.992 screenresolution[1672:10483] starting screenresolution argv=screenresolution list 14:50:06 INFO - Available Modes on Display 0 14:50:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:50:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:50:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:50:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:50:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:50:07 INFO - Intel Iris: 14:50:07 INFO - Chipset Model: Intel Iris 14:50:07 INFO - Type: GPU 14:50:07 INFO - Bus: Built-In 14:50:07 INFO - VRAM (Dynamic, Max): 1536 MB 14:50:07 INFO - Vendor: Intel (0x8086) 14:50:07 INFO - Device ID: 0x0a2e 14:50:07 INFO - Revision ID: 0x0009 14:50:07 INFO - Displays: 14:50:07 INFO - Display: 14:50:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:50:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:50:07 INFO - Display Serial Number: 1600x1200 60 14:50:07 INFO - Main Display: Yes 14:50:07 INFO - Mirror: Off 14:50:07 INFO - Online: Yes 14:50:07 INFO - Rotation: Supported 14:50:07 INFO - Return code: 0 14:50:07 INFO - Running post-action listener: _resource_record_post_action 14:50:07 INFO - [mozharness: 2016-10-12 21:50:07.230155Z] Finished run-tests step (success) 14:50:07 INFO - Running post-run listener: _resource_record_post_run 14:50:07 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 38320640; Write bytes: 711837696; Read time: 244; Write time: 2040 14:50:07 INFO - TinderboxPrint: CPU usage
55.1% 14:50:07 INFO - TinderboxPrint: I/O read bytes / time
38,320,640 / 244 14:50:07 INFO - TinderboxPrint: I/O write bytes / time
711,837,696 / 2,040 14:50:07 INFO - TinderboxPrint: CPU idle
2,177.4 (44.9%) 14:50:07 INFO - TinderboxPrint: CPU system
987.4 (20.4%) 14:50:07 INFO - TinderboxPrint: CPU user
1,685.0 (34.7%) 14:50:07 INFO - TinderboxPrint: Swap in / out
445,652,992 / 0 14:50:07 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 413518336; Write bytes: 411811840; Read time: 31085; Write time: 878 14:50:07 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 29987328; Write bytes: 298686464; Read time: 180; Write time: 1155 14:50:07 INFO - Running post-run listener: _upload_blobber_files 14:50:07 INFO - Blob upload gear active. 14:50:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:07 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 14:50:07 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'] 14:50:07 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 14:50:08 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:50:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:09 INFO - (blobuploader) - INFO - Done attempting. 14:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:50:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:10 INFO - (blobuploader) - INFO - Done attempting. 14:50:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:13 INFO - (blobuploader) - INFO - Done attempting. 14:50:13 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:13 INFO - Return code: 0 14:50:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ac3be81fbb7c6599a17dca4dd0299ea40ef111d941e8b155c39537d3827c1c82c7fd943ca8940ddd6e772b71bb50eadb185a76826e39a60e33d351d6a0baac8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68351d1f44f4d1887268eb2ca45dced22f94f5eeaadef20726d72cade992b7a38e720c74b1132c40e1d4a0ca4c36a2f5408e489f0707eb58970f7c538a426aea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce4d4e96d298bf6607585989925950d96810c5d91951de3cb806d61c41ce6cb75116d4175016cddb867c26b66372523da7dea0ba6f80fb6a5a7871a6ac3aed2"} 14:50:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:13 INFO - Contents: 14:50:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ac3be81fbb7c6599a17dca4dd0299ea40ef111d941e8b155c39537d3827c1c82c7fd943ca8940ddd6e772b71bb50eadb185a76826e39a60e33d351d6a0baac8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68351d1f44f4d1887268eb2ca45dced22f94f5eeaadef20726d72cade992b7a38e720c74b1132c40e1d4a0ca4c36a2f5408e489f0707eb58970f7c538a426aea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce4d4e96d298bf6607585989925950d96810c5d91951de3cb806d61c41ce6cb75116d4175016cddb867c26b66372523da7dea0ba6f80fb6a5a7871a6ac3aed2"} 14:50:13 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:13 INFO - Copying logs to upload dir... 14:50:13 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.003771 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 14:50:13.395284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:13.402363) ========= 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.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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/8ac3be81fbb7c6599a17dca4dd0299ea40ef111d941e8b155c39537d3827c1c82c7fd943ca8940ddd6e772b71bb50eadb185a76826e39a60e33d351d6a0baac8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68351d1f44f4d1887268eb2ca45dced22f94f5eeaadef20726d72cade992b7a38e720c74b1132c40e1d4a0ca4c36a2f5408e489f0707eb58970f7c538a426aea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce4d4e96d298bf6607585989925950d96810c5d91951de3cb806d61c41ce6cb75116d4175016cddb867c26b66372523da7dea0ba6f80fb6a5a7871a6ac3aed2"} build_url:https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009897 build_url: 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ac3be81fbb7c6599a17dca4dd0299ea40ef111d941e8b155c39537d3827c1c82c7fd943ca8940ddd6e772b71bb50eadb185a76826e39a60e33d351d6a0baac8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68351d1f44f4d1887268eb2ca45dced22f94f5eeaadef20726d72cade992b7a38e720c74b1132c40e1d4a0ca4c36a2f5408e489f0707eb58970f7c538a426aea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce4d4e96d298bf6607585989925950d96810c5d91951de3cb806d61c41ce6cb75116d4175016cddb867c26b66372523da7dea0ba6f80fb6a5a7871a6ac3aed2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:13.432015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:13.432347) ========= 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.IePIKDmaKG/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.4GQ79RMD0b/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.004831 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:13.480675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 14:50:13.484068) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 14:50:13.484424) ========= ========= Total master_lag: 0.18 =========