builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1475601558.5 results: success (0) buildid: 20161004081403 builduid: 57406a53af214c129e7930466c562528 revision: 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.499550) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.500029) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.500306) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.529127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.529467) ========= 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.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-04 10:19:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.9M=0s 2016-10-04 10:19:18 (28.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132610 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:18.684509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 10:19:18.684834) ========= 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.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.029742 ========= master_lag: 1.20 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 10:19:19.911573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-04 10:19:19.912048) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-04 10:19:19,989 truncating revision to first 12 chars 2016-10-04 10:19:19,989 Setting DEBUG logging. 2016-10-04 10:19:19,989 attempt 1/10 2016-10-04 10:19:19,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/067c15e2620f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 10:19:20,991 unpacking tar archive at: autoland-067c15e2620f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.255141 ========= master_lag: 2.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-04 10:19:23.341241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:23.341599) ========= script_repo_revision: 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:23.348037) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:23.348320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 10:19:24.194439) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 10:19:24.194733) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:19:24 INFO - MultiFileLogger online at 20161004 10:19:24 in /builds/slave/test 10:19:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:19:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:19:24 INFO - {'append_to_log': False, 10:19:24 INFO - 'base_work_dir': '/builds/slave/test', 10:19:24 INFO - 'blob_upload_branch': 'Autoland', 10:19:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:19:24 INFO - 'branch': 'Autoland', 10:19:24 INFO - 'buildbot_json_path': 'buildprops.json', 10:19:24 INFO - 'config_files': ('talos/mac_config.py',), 10:19:24 INFO - 'default_actions': ('clobber', 10:19:24 INFO - 'read-buildbot-config', 10:19:24 INFO - 'download-and-extract', 10:19:24 INFO - 'populate-webroot', 10:19:24 INFO - 'create-virtualenv', 10:19:24 INFO - 'install', 10:19:24 INFO - 'run-tests'), 10:19:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:19:24 INFO - 'download_minidump_stackwalk': True, 10:19:24 INFO - 'download_symbols': 'ondemand', 10:19:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:19:24 INFO - 'tooltool.py': '/tools/tooltool.py', 10:19:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:19:24 INFO - '/tools/misc-python/virtualenv.py')}, 10:19:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:19:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:19:24 INFO - 'installer_path': 'installer.exe', 10:19:24 INFO - 'log_level': 'info', 10:19:24 INFO - 'log_name': 'talos', 10:19:24 INFO - 'log_to_console': True, 10:19:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:19:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:19:24 INFO - 'opt_config_files': (), 10:19:24 INFO - 'pip_index': False, 10:19:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:24 INFO - 'cmd': ('bash', 10:19:24 INFO - '-c', 10:19:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:24 INFO - 'enabled': True, 10:19:24 INFO - 'halt_on_failure': False, 10:19:24 INFO - 'name': 'check_screen_resolution'},), 10:19:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:24 INFO - 'cmd': ('bash', 10:19:24 INFO - '-c', 10:19:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:24 INFO - 'enabled': True, 10:19:24 INFO - 'halt_on_failure': False, 10:19:24 INFO - 'name': 'check_screen_resolution'},), 10:19:24 INFO - 'run_cmd_checks_enabled': True, 10:19:24 INFO - 'sps_profile': False, 10:19:24 INFO - 'sps_profile_interval': 0, 10:19:24 INFO - 'suite': 'dromaeojs-e10s', 10:19:24 INFO - 'system_bits': '32', 10:19:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:19:24 INFO - 'title': 't-yosemite-r7-0195', 10:19:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:19:24 INFO - 'use_talos_json': True, 10:19:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:19:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:19:24 INFO - 'work_dir': 'build'} 10:19:24 INFO - [mozharness: 2016-10-04 17:19:24.397753Z] Running clobber step. 10:19:24 INFO - Running pre-action listener: _resource_record_pre_action 10:19:24 INFO - Running main action method: clobber 10:19:24 INFO - rmtree: /builds/slave/test/build 10:19:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:19:26 INFO - Running post-action listener: _resource_record_post_action 10:19:26 INFO - [mozharness: 2016-10-04 17:19:26.486587Z] Finished clobber step (success) 10:19:26 INFO - [mozharness: 2016-10-04 17:19:26.486746Z] Running read-buildbot-config step. 10:19:26 INFO - Running pre-action listener: _resource_record_pre_action 10:19:26 INFO - Running main action method: read_buildbot_config 10:19:26 INFO - Using buildbot properties: 10:19:26 INFO - { 10:19:26 INFO - "project": "", 10:19:26 INFO - "product": "firefox", 10:19:26 INFO - "script_repo_revision": "production", 10:19:26 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 10:19:26 INFO - "repository": "", 10:19:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 10:19:26 INFO - "buildid": "20161004081403", 10:19:26 INFO - "pgo_build": "False", 10:19:26 INFO - "basedir": "/builds/slave/test", 10:19:26 INFO - "buildnumber": 233, 10:19:26 INFO - "slavename": "t-yosemite-r7-0195", 10:19:26 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:19:26 INFO - "platform": "yosemite_r7", 10:19:26 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 10:19:26 INFO - "branch": "autoland", 10:19:26 INFO - "repo_path": "integration/autoland", 10:19:26 INFO - "revision": "067c15e2620f0f518d7ee70a06cdfd226f13b3f2", 10:19:26 INFO - "stage_platform": "macosx64", 10:19:26 INFO - "builduid": "57406a53af214c129e7930466c562528", 10:19:26 INFO - "slavebuilddir": "test" 10:19:26 INFO - } 10:19:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:19:26 INFO - Running post-action listener: _resource_record_post_action 10:19:26 INFO - [mozharness: 2016-10-04 17:19:26.490088Z] Finished read-buildbot-config step (success) 10:19:26 INFO - [mozharness: 2016-10-04 17:19:26.490196Z] Running download-and-extract step. 10:19:26 INFO - Running pre-action listener: _resource_record_pre_action 10:19:26 INFO - Running main action method: download_and_extract 10:19:26 INFO - mkdir: /builds/slave/test/build/tests 10:19: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')]} 10:19:26 INFO - trying https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:19:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/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 10:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/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 10:19:27 INFO - Downloaded 1057 bytes. 10:19:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:19:27 INFO - Using the following test package requirements: 10:19:27 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:19:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:19:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:19:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'jsshell-mac.zip'], 10:19:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:19:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:19:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:19:27 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:19:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:19:27 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:19:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:19:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:19:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:19:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:19:27 INFO - Http code: 200 10:19:27 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 10:19:27 INFO - x-amz-cf-id: h8vFKhJimCDgHPHpkr0jz-Bai0gYRIc8AQvQrWpwAqnrmZgOT4AXDw== 10:19:27 INFO - x-amz-version-id: 6zudPjRtvWk0GN69m1KDUmcnjRr1LGbV 10:19:28 INFO - Expected file size: 16477119 10:19:28 INFO - Obtained file size: 16477119 10:19:34 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 10:19:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:19:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:19:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:19:35 INFO - Http code: 200 10:19:35 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:19:35 INFO - x-amz-cf-id: ih301y4C-mtZtajLBwmnCeUJwhqwmJPT9vjpq-dFwV3FC_Xgth1aFg== 10:19:35 INFO - x-amz-version-id: 6zudPjRtvWk0GN69m1KDUmcnjRr1LGbV 10:19:35 INFO - Expected file size: 16477119 10:19:35 INFO - Obtained file size: 16477119 10:19:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:19:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:19:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:19:43 INFO - Http code: 200 10:19:43 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 10:19:43 INFO - x-amz-cf-id: YMho1E0t0aMxAEQuFtBR1PJpo7ElLBHggrLh6XJXrXd9XokVzBZGGQ== 10:19:43 INFO - x-amz-version-id: DPzi9k3lUGA6WBlOj98lCYlu2ijX99sH 10:19:43 INFO - Expected file size: 14409006 10:19:43 INFO - Obtained file size: 14409006 10:19: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')]} 10:19:43 INFO - trying https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:19:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:19:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/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 10:19:46 INFO - Downloaded 111629272 bytes. 10:19:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:19:46 INFO - mkdir: /builds/slave/test/properties 10:19:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:19:46 INFO - Writing to file /builds/slave/test/properties/build_url 10:19:46 INFO - Contents: 10:19:46 INFO - build_url:https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:19:47 INFO - Running post-action listener: _resource_record_post_action 10:19:47 INFO - Running post-action listener: set_extra_try_arguments 10:19:47 INFO - [mozharness: 2016-10-04 17:19:47.641814Z] Finished download-and-extract step (success) 10:19:47 INFO - [mozharness: 2016-10-04 17:19:47.641988Z] Running populate-webroot step. 10:19:47 INFO - Running pre-action listener: _resource_record_pre_action 10:19:47 INFO - Running main action method: populate_webroot 10:19:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:19:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:19:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:19:47 INFO - '--tppagecycles', 10:19:47 INFO - '7'], 10:19:47 INFO - 'tests': ['tsvgm']}}, 10:19:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:19:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:19:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:19:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:19:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['damp', 'tps']}, 10:19:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'tests': ['damp', 'tps']}, 10:19:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['dromaeo_dom']}, 10:19:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:19:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['basic_compositor_video']}, 10:19:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:19:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'other-e10s': {'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['a11yr', 10:19:47 INFO - 'ts_paint', 10:19:47 INFO - 'tpaint', 10:19:47 INFO - 'sessionrestore', 10:19:47 INFO - 'sessionrestore_no_auto_restore', 10:19:47 INFO - 'tabpaint']}, 10:19:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['tsvgx', 10:19:47 INFO - 'tsvgr_opacity', 10:19:47 INFO - 'tart', 10:19:47 INFO - 'tscrollx', 10:19:47 INFO - 'cart']}, 10:19:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:19:47 INFO - 'tsvgr_opacity', 10:19:47 INFO - 'tart', 10:19:47 INFO - 'tscrollx', 10:19:47 INFO - 'cart']}, 10:19:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'talos_options': ['--disable-e10s'], 10:19:47 INFO - 'tests': ['tp5o']}, 10:19:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'tests': ['tp5o']}, 10:19:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'talos_options': ['--disable-e10s', 10:19:47 INFO - '--xperf_path', 10:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:47 INFO - 'tests': ['tp5n']}, 10:19:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:47 INFO - 'talos_options': ['--xperf_path', 10:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:47 INFO - 'tests': ['tp5n']}}, 10:19:47 INFO - 'talos.zip': {'path': '', 10:19:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:19:47 INFO - Running post-action listener: _resource_record_post_action 10:19:47 INFO - [mozharness: 2016-10-04 17:19:47.660301Z] Finished populate-webroot step (success) 10:19:47 INFO - [mozharness: 2016-10-04 17:19:47.660412Z] Running create-virtualenv step. 10:19:47 INFO - Running pre-action listener: _resource_record_pre_action 10:19:47 INFO - Running main action method: create_virtualenv 10:19:47 INFO - Creating virtualenv /builds/slave/test/build/venv 10:19:47 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 10:19:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:19:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:19:47 INFO - Using real prefix '/tools/python27' 10:19:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:19:48 INFO - Installing distribute.............................................................................................................................................................................................done. 10:19:51 INFO - Installing pip.................done. 10:19:51 INFO - Return code: 0 10:19:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:19:51 INFO - Reading from file tmpfile_stdout 10:19:51 INFO - Output received: 10:19:51 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) 10:19:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:19:51 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 10:19:51 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 10:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:51 INFO - 'HOME': '/Users/cltbld', 10:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:51 INFO - 'LOGNAME': 'cltbld', 10:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:51 INFO - 'MOZ_NO_REMOTE': '1', 10:19:51 INFO - 'NO_EM_RESTART': '1', 10:19:51 INFO - 'PAGER': '/bin/cat', 10:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:51 INFO - 'PWD': '/builds/slave/test', 10:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:51 INFO - 'SHELL': '/bin/bash', 10:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:19:51 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:51 INFO - 'USER': 'cltbld', 10:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:51 INFO - 'XPC_FLAGS': '0x0', 10:19:51 INFO - 'XPC_SERVICE_NAME': '0', 10:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:51 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 10:19:51 INFO - Cleaning up... 10:19:51 INFO - Return code: 0 10:19:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:19:51 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 10:19:51 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 10:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:51 INFO - 'HOME': '/Users/cltbld', 10:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:51 INFO - 'LOGNAME': 'cltbld', 10:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:51 INFO - 'MOZ_NO_REMOTE': '1', 10:19:51 INFO - 'NO_EM_RESTART': '1', 10:19:51 INFO - 'PAGER': '/bin/cat', 10:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:51 INFO - 'PWD': '/builds/slave/test', 10:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:51 INFO - 'SHELL': '/bin/bash', 10:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:19:51 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:51 INFO - 'USER': 'cltbld', 10:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:51 INFO - 'XPC_FLAGS': '0x0', 10:19:51 INFO - 'XPC_SERVICE_NAME': '0', 10:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:52 INFO - Downloading/unpacking psutil>=3.1.1 10:19:52 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 10:19:52 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 10:19:52 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 10:19:52 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 10:19:52 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 10:19:52 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 10:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:19:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:55 INFO - Installing collected packages: psutil 10:19:55 INFO - Running setup.py install for psutil 10:19:55 INFO - building 'psutil._psutil_osx' extension 10:19:55 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 10:19:56 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 10:19:56 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 10:19:56 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 10:19:56 INFO - building 'psutil._psutil_posix' extension 10:19:56 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 10:19:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:19:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:19:56 INFO - ^ 10:19:56 INFO - 1 warning generated. 10:19:56 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 10:19:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:56 INFO - Successfully installed psutil 10:19:56 INFO - Cleaning up... 10:19:56 INFO - Return code: 0 10:19:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:56 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:19:56 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 10:19:56 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 10:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:56 INFO - 'HOME': '/Users/cltbld', 10:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:56 INFO - 'LOGNAME': 'cltbld', 10:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:56 INFO - 'MOZ_NO_REMOTE': '1', 10:19:56 INFO - 'NO_EM_RESTART': '1', 10:19:56 INFO - 'PAGER': '/bin/cat', 10:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:56 INFO - 'PWD': '/builds/slave/test', 10:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:56 INFO - 'SHELL': '/bin/bash', 10:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:19:56 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:56 INFO - 'USER': 'cltbld', 10:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:56 INFO - 'XPC_FLAGS': '0x0', 10:19:56 INFO - 'XPC_SERVICE_NAME': '0', 10:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:19:59 INFO - Installing collected packages: mozsystemmonitor 10:19:59 INFO - Running setup.py install for mozsystemmonitor 10:19:59 INFO - Successfully installed mozsystemmonitor 10:19:59 INFO - Cleaning up... 10:19:59 INFO - Return code: 0 10:19:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:59 INFO - 'HOME': '/Users/cltbld', 10:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:59 INFO - 'LOGNAME': 'cltbld', 10:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:59 INFO - 'MOZ_NO_REMOTE': '1', 10:19:59 INFO - 'NO_EM_RESTART': '1', 10:19:59 INFO - 'PAGER': '/bin/cat', 10:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:59 INFO - 'PWD': '/builds/slave/test', 10:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:59 INFO - 'SHELL': '/bin/bash', 10:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:19:59 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:59 INFO - 'USER': 'cltbld', 10:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:59 INFO - 'XPC_FLAGS': '0x0', 10:19:59 INFO - 'XPC_SERVICE_NAME': '0', 10:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:00 INFO - Downloading/unpacking jsonschema==2.5.1 10:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:20:02 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 10:20:02 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 10:20:02 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 10:20:02 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 10:20:02 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 10:20:02 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 10:20:02 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:03 INFO - warning: no files found matching '*.txt' 10:20:03 INFO - no previously-included directories found matching 'build' 10:20:03 INFO - no previously-included directories found matching 'dist' 10:20:03 INFO - no previously-included directories found matching '.git*' 10:20:03 INFO - Installing collected packages: jsonschema, functools32 10:20:03 INFO - Running setup.py install for functools32 10:20:03 INFO - warning: no files found matching '*.txt' 10:20:03 INFO - no previously-included directories found matching 'build' 10:20:03 INFO - no previously-included directories found matching 'dist' 10:20:03 INFO - no previously-included directories found matching '.git*' 10:20:03 INFO - Successfully installed jsonschema functools32 10:20:03 INFO - Cleaning up... 10:20:03 INFO - Return code: 0 10:20:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:03 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 10:20:03 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 10:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:03 INFO - 'HOME': '/Users/cltbld', 10:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:03 INFO - 'LOGNAME': 'cltbld', 10:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:03 INFO - 'MOZ_NO_REMOTE': '1', 10:20:03 INFO - 'NO_EM_RESTART': '1', 10:20:03 INFO - 'PAGER': '/bin/cat', 10:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:03 INFO - 'PWD': '/builds/slave/test', 10:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:03 INFO - 'SHELL': '/bin/bash', 10:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:03 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:03 INFO - 'USER': 'cltbld', 10:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:03 INFO - 'XPC_FLAGS': '0x0', 10:20:03 INFO - 'XPC_SERVICE_NAME': '0', 10:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:20:03 INFO - Cleaning up... 10:20:03 INFO - Return code: 0 10:20:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:03 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 10:20:03 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 10:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:03 INFO - 'HOME': '/Users/cltbld', 10:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:03 INFO - 'LOGNAME': 'cltbld', 10:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:03 INFO - 'MOZ_NO_REMOTE': '1', 10:20:03 INFO - 'NO_EM_RESTART': '1', 10:20:03 INFO - 'PAGER': '/bin/cat', 10:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:03 INFO - 'PWD': '/builds/slave/test', 10:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:03 INFO - 'SHELL': '/bin/bash', 10:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:03 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:03 INFO - 'USER': 'cltbld', 10:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:03 INFO - 'XPC_FLAGS': '0x0', 10:20:03 INFO - 'XPC_SERVICE_NAME': '0', 10:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:03 INFO - Downloading/unpacking blobuploader==1.2.4 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - Downloading blobuploader-1.2.4.tar.gz 10:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:20:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:20:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:20:07 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 10:20:07 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 10:20:07 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 10:20:07 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 10:20:07 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 10:20:07 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 10:20:07 INFO - Downloading docopt-0.6.1.tar.gz 10:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:20:07 INFO - Installing collected packages: blobuploader, requests, docopt 10:20:07 INFO - Running setup.py install for blobuploader 10:20:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:20:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:20:07 INFO - Running setup.py install for requests 10:20:08 INFO - Running setup.py install for docopt 10:20:08 INFO - Successfully installed blobuploader requests docopt 10:20:08 INFO - Cleaning up... 10:20:08 INFO - Return code: 0 10:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:08 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 10:20:08 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 10:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:08 INFO - 'HOME': '/Users/cltbld', 10:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:08 INFO - 'LOGNAME': 'cltbld', 10:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:08 INFO - 'MOZ_NO_REMOTE': '1', 10:20:08 INFO - 'NO_EM_RESTART': '1', 10:20:08 INFO - 'PAGER': '/bin/cat', 10:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:08 INFO - 'PWD': '/builds/slave/test', 10:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:08 INFO - 'SHELL': '/bin/bash', 10:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:08 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:08 INFO - 'USER': 'cltbld', 10:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:08 INFO - 'XPC_FLAGS': '0x0', 10:20:08 INFO - 'XPC_SERVICE_NAME': '0', 10:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:08 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CI9WFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:08 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1XwifN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PIUyYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-gzXdMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-X_Hq5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Bxq2NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hhj8Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-L2apPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-k3Mfks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-RTHgGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Ygk39Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-C9fEy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Zw8ovt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-B4QOxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ZX8RcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-styn_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-d88SgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:20:10 INFO - Running setup.py install for manifestparser 10:20:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:20:10 INFO - Running setup.py install for mozcrash 10:20:10 INFO - Running setup.py install for mozdebug 10:20:10 INFO - Running setup.py install for mozdevice 10:20:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Running setup.py install for mozfile 10:20:11 INFO - Running setup.py install for mozhttpd 10:20:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Running setup.py install for mozinfo 10:20:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Running setup.py install for mozInstall 10:20:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:20:11 INFO - Running setup.py install for mozleak 10:20:11 INFO - Running setup.py install for mozlog 10:20:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Running setup.py install for moznetwork 10:20:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Running setup.py install for mozprocess 10:20:12 INFO - Running setup.py install for mozprofile 10:20:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Running setup.py install for mozrunner 10:20:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:20:12 INFO - Running setup.py install for mozscreenshot 10:20:12 INFO - Running setup.py install for moztest 10:20:12 INFO - Running setup.py install for mozversion 10:20:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:20:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:20:13 INFO - Cleaning up... 10:20:13 INFO - Return code: 0 10:20:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:13 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:13 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 10:20:13 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 10:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:13 INFO - 'HOME': '/Users/cltbld', 10:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:13 INFO - 'LOGNAME': 'cltbld', 10:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:13 INFO - 'MOZ_NO_REMOTE': '1', 10:20:13 INFO - 'NO_EM_RESTART': '1', 10:20:13 INFO - 'PAGER': '/bin/cat', 10:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:13 INFO - 'PWD': '/builds/slave/test', 10:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:13 INFO - 'SHELL': '/bin/bash', 10:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:13 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:13 INFO - 'USER': 'cltbld', 10:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:13 INFO - 'XPC_FLAGS': '0x0', 10:20:13 INFO - 'XPC_SERVICE_NAME': '0', 10:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-i5_O0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:13 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)) 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Gtv0hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:13 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)) 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-nbx9sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:13 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)) 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-P0csX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:13 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)) 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-yq7glX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:13 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)) 10:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-4UIFoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-3dNAvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XAkkJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-kaSmsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-JH7Y44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Jbnnrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-IosxTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6j3KyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-NqxPsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:14 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)) 10:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-J5eKr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:15 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)) 10:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bJ4XQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:15 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)) 10:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-A83kdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:15 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)) 10:20:15 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)) 10:20:15 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)) 10:20:15 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)) 10:20:15 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)) 10:20:15 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)) 10:20:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:18 INFO - Downloading blessings-1.6.tar.gz 10:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:20:18 INFO - Installing collected packages: blessings 10:20:18 INFO - Running setup.py install for blessings 10:20:18 INFO - Successfully installed blessings 10:20:18 INFO - Cleaning up... 10:20:18 INFO - Return code: 0 10:20:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:18 INFO - Reading from file tmpfile_stdout 10:20:18 INFO - Current package versions: 10:20:18 INFO - blessings == 1.6 10:20:18 INFO - blobuploader == 1.2.4 10:20:18 INFO - docopt == 0.6.1 10:20:18 INFO - functools32 == 3.2.3-2 10:20:18 INFO - jsonschema == 2.5.1 10:20:18 INFO - manifestparser == 1.1 10:20:18 INFO - mozInstall == 1.12 10:20:18 INFO - mozcrash == 0.17 10:20:18 INFO - mozdebug == 0.1 10:20:18 INFO - mozdevice == 0.48 10:20:18 INFO - mozfile == 1.2 10:20:18 INFO - mozhttpd == 0.7 10:20:18 INFO - mozinfo == 0.9 10:20:18 INFO - mozleak == 0.1 10:20:18 INFO - mozlog == 3.4 10:20:18 INFO - moznetwork == 0.27 10:20:18 INFO - mozprocess == 0.23 10:20:18 INFO - mozprofile == 0.28 10:20:18 INFO - mozrunner == 6.12 10:20:18 INFO - mozscreenshot == 0.1 10:20:18 INFO - mozsystemmonitor == 0.3 10:20:18 INFO - moztest == 0.7 10:20:18 INFO - mozversion == 1.4 10:20:18 INFO - psutil == 3.1.1 10:20:18 INFO - requests == 1.2.3 10:20:18 INFO - wsgiref == 0.1.2 10:20:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20: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')]} 10:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:19 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:19 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 10:20:19 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 10:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:19 INFO - 'HOME': '/Users/cltbld', 10:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:19 INFO - 'LOGNAME': 'cltbld', 10:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:19 INFO - 'MOZ_NO_REMOTE': '1', 10:20:19 INFO - 'NO_EM_RESTART': '1', 10:20:19 INFO - 'PAGER': '/bin/cat', 10:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:19 INFO - 'PWD': '/builds/slave/test', 10:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:19 INFO - 'SHELL': '/bin/bash', 10:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:19 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:19 INFO - 'USER': 'cltbld', 10:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:19 INFO - 'XPC_FLAGS': '0x0', 10:20:19 INFO - 'XPC_SERVICE_NAME': '0', 10:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 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)) 10:20:19 INFO - Cleaning up... 10:20:19 INFO - Return code: 0 10:20:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:19 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 0x109d13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c89570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c34af130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, '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.pVs8iszYKI/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.sX7gFOyXOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:20:19 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 10:20:19 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 10:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:19 INFO - 'HOME': '/Users/cltbld', 10:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:19 INFO - 'LOGNAME': 'cltbld', 10:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:19 INFO - 'MOZ_NO_REMOTE': '1', 10:20:19 INFO - 'NO_EM_RESTART': '1', 10:20:19 INFO - 'PAGER': '/bin/cat', 10:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:19 INFO - 'PWD': '/builds/slave/test', 10:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:19 INFO - 'SHELL': '/bin/bash', 10:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:19 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:19 INFO - 'USER': 'cltbld', 10:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:19 INFO - 'XPC_FLAGS': '0x0', 10:20:19 INFO - 'XPC_SERVICE_NAME': '0', 10:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:20:19 INFO - Cleaning up... 10:20:19 INFO - Return code: 0 10:20:19 INFO - Running post-action listener: _resource_record_post_action 10:20:19 INFO - Running post-action listener: _start_resource_monitoring 10:20:19 INFO - Starting resource monitoring. 10:20:19 INFO - [mozharness: 2016-10-04 17:20:19.612202Z] Finished create-virtualenv step (success) 10:20:19 INFO - [mozharness: 2016-10-04 17:20:19.613164Z] Running install step. 10:20:19 INFO - Running pre-action listener: _resource_record_pre_action 10:20:19 INFO - Running main action method: install 10:20:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:19 INFO - Reading from file tmpfile_stdout 10:20:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:20:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:20:19 INFO - Reading from file tmpfile_stdout 10:20:19 INFO - Output received: 10:20:19 INFO - Usage: mozinstall [options] installer 10:20:19 INFO - Options: 10:20:19 INFO - -h, --help show this help message and exit 10:20:19 INFO - -d DEST, --destination=DEST 10:20:19 INFO - Directory to install application into. [default: 10:20:19 INFO - "/builds/slave/test"] 10:20:19 INFO - --app=APP Application being installed. [default: firefox] 10:20:19 INFO - mkdir: /builds/slave/test/build/application 10:20:19 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'] 10:20:19 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 10:20:54 INFO - Reading from file tmpfile_stdout 10:20:54 INFO - Output received: 10:20:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:20:54 INFO - Running post-action listener: _resource_record_post_action 10:20:54 INFO - [mozharness: 2016-10-04 17:20:54.216906Z] Finished install step (success) 10:20:54 INFO - [mozharness: 2016-10-04 17:20:54.217012Z] Running run-tests step. 10:20:54 INFO - Running pre-action listener: _resource_record_pre_action 10:20:54 INFO - Running main action method: run_tests 10:20:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:20:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:20:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:20:54 INFO - 2016-10-04 10:20:54.229 screenresolution[1637:7641] starting screenresolution argv=screenresolution get 10:20:54 INFO - 2016-10-04 10:20:54.232 screenresolution[1637:7641] Display 0: 1600x1200x32@60 10:20:54 INFO - 2016-10-04 10:20:54.237 screenresolution[1638:7643] starting screenresolution argv=screenresolution list 10:20:54 INFO - Available Modes on Display 0 10:20:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:20:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:20:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:20:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:20:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:20:54 INFO - Intel Iris: 10:20:54 INFO - Chipset Model: Intel Iris 10:20:54 INFO - Type: GPU 10:20:54 INFO - Bus: Built-In 10:20:54 INFO - VRAM (Dynamic, Max): 1536 MB 10:20:54 INFO - Vendor: Intel (0x8086) 10:20:54 INFO - Device ID: 0x0a2e 10:20:54 INFO - Revision ID: 0x0009 10:20:54 INFO - Displays: 10:20:54 INFO - Display: 10:20:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:20:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:20:54 INFO - Display Serial Number: 1600x1200 60 10:20:54 INFO - Main Display: Yes 10:20:54 INFO - Mirror: Off 10:20:54 INFO - Online: Yes 10:20:54 INFO - Rotation: Supported 10:20:54 INFO - Return code: 0 10:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:20:54 INFO - Python 2.7.3 10:20:54 INFO - Return code: 0 10:20:54 INFO - grabbing minidump binary from tooltool 10:20: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')]} 10:20:54 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 0x109b27d30>, '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 0x10994ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:20:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:20:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:20:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:20:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:20:54 INFO - Return code: 0 10:20:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:20:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:20:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:20:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:20:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pVs8iszYKI/Render', 10:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:54 INFO - 'HOME': '/Users/cltbld', 10:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:54 INFO - 'LOGNAME': 'cltbld', 10:20:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:20:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:54 INFO - 'MOZ_NO_REMOTE': '1', 10:20:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:20:54 INFO - 'NO_EM_RESTART': '1', 10:20:54 INFO - 'PAGER': '/bin/cat', 10:20:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:54 INFO - 'PWD': '/builds/slave/test', 10:20:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:54 INFO - 'SHELL': '/bin/bash', 10:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sX7gFOyXOV/Listeners', 10:20:54 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:54 INFO - 'USER': 'cltbld', 10:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:54 INFO - 'XPC_FLAGS': '0x0', 10:20:54 INFO - 'XPC_SERVICE_NAME': '0', 10:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:20:54 INFO - mozversion application_buildid: 20161004081403 10:20:54 INFO - mozversion application_changeset: 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 10:20:54 INFO - mozversion application_display_name: Nightly 10:20:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:20:54 INFO - mozversion application_name: Firefox 10:20:54 INFO - mozversion application_remotingname: firefox 10:20:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:20:54 INFO - mozversion application_vendor: Mozilla 10:20:54 INFO - mozversion application_version: 52.0a1 10:20:54 INFO - mozversion platform_buildid: 20161004081403 10:20:54 INFO - mozversion platform_changeset: 067c15e2620f0f518d7ee70a06cdfd226f13b3f2 10:20:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:20:54 INFO - mozversion platform_version: 52.0a1 10:20:54 INFO - using testdate: 1475601654 10:20:54 INFO - actual date: 1475601654 10:20:54 INFO - starting webserver on 'localhost:49275' 10:20:54 INFO - SUITE-START | Running 2 tests 10:20:54 INFO - TEST-START | dromaeo_css 10:20:54 INFO - operating with platform_type : mac_ 10:20:54 INFO - Initialising browser for dromaeo_css test... 10:20:54 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp5m9LvL/profile http://localhost:49275/getInfo.html) 10:20:57 INFO - PROCESS | 1648 | 2016-10-04 10:20:57.829 plugin-container[1650:7809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 10:20:57 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:57 INFO - PROCESS | 1648 | 2016-10-04 10:20:57.833 plugin-container[1650:7809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 10:20:57 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:57 INFO - PROCESS | 1648 | 2016-10-04 10:20:57.833 plugin-container[1650:7809] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:20:57 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:20:57 INFO - PROCESS | 1648 | colorDepth:24 10:20:57 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:20:57 INFO - PROCESS | 1648 | __metrics 10:20:58 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 10:20:58 INFO - TEST-INFO | 1648: exit 0 10:20:58 INFO - Browser initialized. 10:20:58 INFO - Running cycle 1/1 for dromaeo_css test... 10:20:58 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp5m9LvL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:01 INFO - PROCESS | 1654 | 2016-10-04 10:21:01.160 plugin-container[1655:7990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.tsm.portname' 10:21:01 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:01 INFO - PROCESS | 1654 | 2016-10-04 10:21:01.165 plugin-container[1655:7990] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 10:21:01 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:01 INFO - PROCESS | 1654 | 2016-10-04 10:21:01.165 plugin-container[1655:7990] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:21:10 INFO - PROCESS | 1654 | RSS: Main: 240168960 10:21:10 INFO - PROCESS | 1654 | 10:22:00 INFO - PROCESS | 1654 | 1475601720409 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] 10:24:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:24:15 INFO - PROCESS | 1654 | RSS: Main: 253345792 10:24:15 INFO - PROCESS | 1654 | 10:27:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:27:18 INFO - PROCESS | 1654 | RSS: Main: 266989568 10:27:18 INFO - PROCESS | 1654 | 10:30:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:30:21 INFO - PROCESS | 1654 | RSS: Main: 270688256 10:30:21 INFO - PROCESS | 1654 | 10:33:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:33:23 INFO - PROCESS | 1654 | RSS: Main: 270286848 10:33:23 INFO - PROCESS | 1654 | 10:36:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:36:27 INFO - PROCESS | 1654 | RSS: Main: 275660800 10:36:27 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:39:29 INFO - PROCESS | 1654 | RSS: Main: 278261760 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | __start_tp_report 10:39:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:39:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:39:29 INFO - PROCESS | 1654 | |i|pagename|runs| 10:39:29 INFO - PROCESS | 1654 | |0;dojo.html;3331.3004269103485;3398.6771256730603;3405.6764607362306;3439.896803095907;3442.569678790151;4701;4704.670673052888;5141.537261646451;5588.497035266825;5700.287464066992;5557.833265002047;5611.410801865806;5669.603127781057;5696.829095127153;5739.913901291484;5159.587233021359;5202.869928251786;5247.239150323201;5260.710660913648;5261.421243663194;5208.244804503344;5243.554297884684;5287.788488460457;5291.31212942317;5313.068329436209;5243.082460569404;5273.920891186635;5277.973610131944;5278.102722537159;5300.337457817773;4648.768076459741;4948.90102197954;5253.737313134367;5265.447128051557;5273.789048438058;4254.404383386297;5092.592592592584;5206.5314121729225;5207.427183009904;5216.686998780086;5683;5695.45893140151;5743.856403589902;5780.1907732917425;5819.505342045931;6065.878682426327;6197.566170368076;6221.595596286272;6255.155554000243;6278.686065696743;6103.883205879467;6171.567990240685;6195.101710252034;6230.937690623126;6326.65203413812;5814.767409303623;5842.444967728059;5846.386129456431;5856.648601083949;5875.559333050039;6244.469220116341;6254.405831446005;6285.685715714196;6293.716782744787;6368.140301059298;5964.254468191476;6030.33666296707;6100.664463454553;6151.169592105098;6177.907331389943;6132.141500189977;6195.690215489208;6197.783077592263;6202.534809889276;6261.812145635638;6229.283632382243;6233.657148856766;6235.251769787564;6248.187735594343;6257.624542527463;5988.371221021817;6077.483413909778;6218.9378106219265;6257.56197066201;6270.717817698214;4874.561289483892;4880.438749543777;4913.484084171182;4938.209886418156;4953.380827396575;4358.455193100863;4394.802233899482;4409.625181859577;4419.9337009944875;4445.333200020023;5621.15682647598;5718.027935251017;5798.768049277982;5809.331926828384;5864.794732184353;6144.494598823317;6193.876122477525;6196.81409557714;6258.280297765724;6318.905216421757;5822.94177058224;5837.9124313135335;5862.472377486047;5862.970685146547;5877.676727780014;5308.522232999048;5319.1223448131395;5320.600954928396;5322.547583455373;5419.918701219485;6277.435030847246;6295.433410993032;6302.117703521419;6304.306526282106;6370.044493326038;6210.4100110489435;6239.968800156152;6245.781397651061;6257.1240026395435;6257.5932564383165;5481.561475082063;6019.789307374221;6196.938030619636;6293.307736148929;6307.148534947723;6106.339326076981;6199.690015499117;6202.782902598387;6214.844628884314;6240.219972503437;6037;6247.437730604267;6256.468200202851;6258.687065646791;6286.214223222098;5947.494462970523;5997.610155339889;6075.787347442818;6157;6159.106929495288;6075.1798507200965;6095.420935011167;6105.004892149239;6126.142340072304;6146.494108943338;5726.4273572642405;5934.821955341346;5935.643861368159;5956.374580668968;5971.940280597312;5855.824325270249;6080.179175811209;6103.23709536308;6113.816585502442;6135.263768347827;5796.739146738322;5858.179854820243;5888.793892213752;5906.527477801851;5925.40745925404 10:39:29 INFO - PROCESS | 1654 | |1;ext.html;467.5978658353817;470.34387030093023;496.3274762696546;501.64383287865616;520.287206527058;39585;41414.58585414141;41439.79280103603;43224.783876080655;43242.56757432433;42230.15539689212;42621.36067958975;42630.14739705204;42769;42808;9653.276004299689;9791.314607977445;9791.804163916717;9844.114029737322;9900.950495247514;9717.659881904136;9817.607295708163;9838.06538378853;9841.753956151082;9847.882705218377;9807.852882206773;9858.359206651581;9863.457509836991;9888.653897113603;9913.504324783731;9838.458884761336;9850.014998500164;9853.90146098537;9859.852102218472;9867.210623150131;8284.25441710243;8388.203120703523;9809.411435313905;9812.90187098134;9836.803263934682;40011;40158.59841401607;40203.59796402057;40358;40480.39279410811;3287.474004159323;3348.11275012122;3516.507688923553;3821.9426708599394;3963.6432721055244;30467.542986854995;30883;31164.688353116406;31202.843985779928;32016.879409220564;2841.1618572520947;3207.951880721791;3249.382617302705;3262.298605799741;3363.0751543325537;441957;442937;447539.99999999674;448190;449379;54927;55193.44806551883;55216.17175742367;55344.44655553393;55383.44616553863;47543.76228118837;57910;58034;58273.12590311149;58512;15710;15910;15916.442924497587;15954.361825527081;15994;12365.75268494643;12375.566855160027;12390.380480975915;12405.317707526172;12440.377981100908;11239.719007024889;14890.925545372203;14989.325480353411;15016.399344026335;15154.545363639108;7028.543144695578;7089.291070892869;7235.710571577078;7243.637818109073;7250.694874922459;824.9340052795762;925.7222833150028;946.9053094690476;963.7783309838776;1022.5654097008742;24807.379815504395;25179;25385.873070634898;25837.612435813477;26550.937962481283;990.6285143071349;999.3404353126901;1009.4044513736784;1048.5700862646431;1057.947581691516;12879.678008049686;14812.777808332883;15241.23793810305;15361.001534900195;15535.84464155344;615937;620641;622079.6688049682;625158.7357137789;625781.8710906416;55896.44103558912;56010.879782404125;56256.43743562512;56315.15527267094;56350.718246408505;1358.9456421743216;1367.7196174784146;1371.8559551246976;1393.9303034848217;1394.7768357063023;28156.43687126287;28876.855615721786;29456.705432945397;29780.064295820703;29785.702142978294;46562.53437465582;46762.29856552086;46978;46995.76502117467;47028.529714703785;386269;468096;469041.23835046217;469122;471908;37473.437898432094;37855.43216851805;37894.431583525715;38109.618903811715;38262.6173738259;42650.61195389531;46136.53863461457;46231;46380;47280.054398912514;38270.42594361031;38550.807245964716;39211.2157756849;39252.41121383238;39326.82019539304;33815.66184338224;38143.61856381401;38248.426273605364;39474.60525394824;39504.407433887936;36202.81898590596;37474.25051499009;38228;38389.232215354976;38395.040123996005;30151.09546713602;31473.842630787614;34409;34493.82753086218;34670.65329346674;20188.293409730588;21144.365669029954;23815.285541433786;24481.38796530101;26322.473550529267 10:39:29 INFO - PROCESS | 1654 | |2;jquery.html;369.09387453800923;370.1763576043304;376.1386425086552;382.0791891541386;396.9263143197651;17641.735373969368;19154.233830646775;21044.473888152803;21117.47206319841;21274.46813829655;18301.542461438472;18801.96589187594;20177.596448071028;20277.290294839684;20643.896780516116;4888.486708895568;5903.4686878180955;5915.0240210365455;6128.938710612903;6220.564560480768;6059.242594675666;6185.783497577586;6189.81430557084;6193.256809182904;6211.0372892201785;5798.768049278024;6009.57932944694;6053.424924632153;6056.182415373934;6090.086487026959;5476.890462190754;5507.788286576947;5857.506335884323;5916.083007133858;6024.457798798107;5057.216131499624;5187.299714538527;5224.164133738622;5229.5293423591675;5229.660072095302;18624.62750744991;23133.76866231333;24852;25493.49013019748;25520.74479255202;2183.9017244224046;2396.9041238350446;3021.4108248891475;3062.892798752055;3120.5787218725636;15263.542093737206;15402.075875447508;15412.691746165126;15628.296726647215;15710;1443.501991812823;1601.4795191562673;1807.8011418743927;1895.6398284326071;1901.115981068796;95672.52163739207;123941;124005.37997309955;124249.37875310566;125830;7726.536407815436;9503.239740820718;10836.133109351233;10898.727531811768;11039.006489415831;13391.263480508665;13401.597952061455;13442.798358024638;13468.393922273326;13492.190468571916;8367.288780453608;8491.575421229038;8493.108223636553;8516.957415213008;8662.87005694915;8104.554249516215;8580.742577722678;8619;8770.25452836516;8801.691940782042;5724.713764311851;6452.870942581122;6476.870462590816;6489.740410383626;6521.152250207538;1854.378783107647;1866.5893503429224;1869.6728072587243;1870.111696943968;1902.4768188748205;881.5019513974712;935.0696057422907;958.6309270930742;994.7712026234007;998.9250806189565;15829.525114246591;21025.369238922856;22158.556828863333;22174.1130354784;22287.777122228894;844.3118858130604;896.5338024227365;964.5273815830193;991.6925753016588;1003.7540802503287;7793.532388056735;8589.110395712933;9599.47202903847;10647.73380665474;11261.605843795593;511789;516259;516438;609171;614874;78350;81021.18978810136;109892;135779;138191;957;991.4249735153738;1004.8442491413842;1006.33079002464;1011.3123076308034;18813.717794232824;19086.618267634847;19182.616347673247;19184.71222931685;19194.520136996685;36487.81756091202;50244;54184.45815541794;56336.43663563476;56513;69076;82015.94960126045;84233;84616;85663.57168214368;7445.813854653677;7881.226723987286;7976.521408715495;8092.878606820785;8129.715459958873;11521.193516453768;12283.508659653513;12597;12666.683332916751;12972.351382430652;9785.217182625514;10441.947790261;10730.463476825971;11073.114150868072;11203.495842686936;10696.518656660624;10756.83864742045;11151.330920144817;11192.552297908318;11254.94372528132;8834.469931804113;9738.61045558198;10167.898321016695;11514.88485115138;11638.592649257236;6783.796486105425;7922.851186578029;7952;8023.398245131755;8235.423520353517;7934.960325198337;10606;10933.234673572773;11091.778164436828;11179 10:39:29 INFO - PROCESS | 1654 | |3;mootools.html;1535.5163123616064;1581.7864588280581;1817.518357635227;1913.8181872722087;1924.3938159479762;6964.442844572434;8349.791255218623;8621.051684314729;9158.86261706074;9228.400153989995;8496.02295735989;8618.525981071038;8779.824403511926;8962.86555701665;9088;2330.720313562375;2372.596658568039;2373.4066483379156;2381.4641705616273;2385.940351491209;2347.2371479269223;2364.5507353602848;2394.174009966559;2407.614781634939;2410.819188560856;2376.8455050421753;2382.797462215714;2389.474315650554;2394.078279862248;2404.6272827711646;2337.2637619149914;2357.422431504284;2358.7051618547766;2370.12305446984;2393.2820153953744;2342.121704360865;2354.3878591566145;2357.104300365867;2361.6221404575185;2362.444825466014;9066.093390660948;9129.589168487368;9185.402948808372;9202.953985230099;9206;5262.631615786897;5434.619576629638;5438.564914806806;5443.292371991655;5446.992306423324;3591.94612080819;3694.371956767356;3737.588865224823;3782.678472329855;3818.16000479894;1065.7016035510069;1089.477051015517;1099.1371773158032;1100.4442756407964;1103.2222283290262;11749.412529373583;11988.040956723442;12014.837329280814;12035.759284814343;12040.036797056215;4382.9342559861625;4489.079738653568;4645.349651048856;4648.48866624671;4660.067986402665;8522;9258.768530786649;9326.766830829283;9330.486823224788;9331.16019558243;5252.317198764136;5263.6578622389425;5266.631335806534;5275.261463395128;5279.656822306538;4430.180416622935;4580.404547408815;4706.882327941828;4852.587530059914;4929.482404347492;3645.4714066460215;3686.9078273043388;3772.5284339457567;3792.867249646304;3807.010177353908;1156.1213477756796;1161.35088174792;1164.4119719541577;1169.5380324771668;1169.906407487399;813.2639960835453;905.0542183418344;911.1571796088591;913.1098625844571;946.4605175050156;5691.430856914359;5807.622504537191;5938.049912014057;6012.218411606463;6168.8766224675255;380.7449009163867;431.8574870292796;444.8910017045844;906.6509393883402;999.5651891427252;3585.748997570145;3922.235164143022;3939.625735555118;4016.3774614934146;4076.5515793262716;10779;11042.613508527164;11075.944620276847;11091.057260132819;11273.323600583992;9544.42733435996;10804.351738895533;10919.181061420426;11011.953864382871;11134.10927125828;8212.137725538852;8263.47963871025;8353.289970352587;8364.163583641588;8415.663373464993;4788.928166077442;5904.881902361843;5908.940910591011;5913.024350982212;5927.407259274038;10139.64511242103;10290.3825770454;10295.897041029793;10302.278840481094;10328.586856525955;9771.706848794547;10196.745081373025;10244.129249013917;10423.123837708981;10432.68701938943;6048.879022419615;7035.155781306276;7222.169450513048;7602.961985190039;7758.340541054074;8388.035375931846;8621.525816079926;8669.566521673765;8688.218060374596;8692.391532592657;7593.8860917087395;7679.308862202429;7696.538207707556;7791.688332466638;7829.138794732461;7479.289467500579;7561.546307221584;7606.125295590857;7657;7771.378289736695;6842.521023528305;6968.7212511501;6973.790786276217;6975.162980442379;6995.090638217;4997.550220480174;5056.873578160576;5078.669886457368;5090.41460232078;5187.610929180402;4988.401391833003;4996.300517927566;5021.6735912165595;5105.157648987875;5170.663906846043 10:39:29 INFO - PROCESS | 1654 | |4;prototype.html;296.6499530553946;301.21683622581287;308.091131163069;309.1883805011844;312.7060563070713;4208.494980602324;5498.835034948948;5518.503334699876;5604.159376093588;5631.887362252753;5286.920696189561;5454;5484.533814625752;5504.972475137629;5506.339239291281;3718.5723641781137;4124.401961715549;4161.646260067898;4201.936970945439;4215.241256573816;3893.240818040483;4179.853705120321;4224.577542245782;4233.661307095425;4246.683528106281;3389.7457690673173;3410.369081719877;3761.849526018956;4149.340263894452;4164.7709375984305;3382.374260761767;4151.46031015969;4153.252414565377;4155.563665815076;4172.329708986251;3572.83922223498;3591.982040089783;3604.8644676926683;3618.3667858124722;3623.61951995042;4986.825461108844;5329.267987903947;5374.984380857179;5401.810936617238;5475.397706252309;4713.835015774432;4760.976195119037;4770.356001939763;4790;4801.231802911518;4297.162053399588;4933.629977751683;5000.899802043545;5520;5584.6038490377405;3436.742244331685;3557.3418917500344;3783.5270591176104;3821.541415030214;3949.150932549516;5099.526729536172;5589.608727389084;5764.250647415809;6125.540584456139;6240.188775459206;3230.5154226866157;3329.4839299908554;3535.0910508325214;3729.533808273966;4000.8199631016673;3498.7201023918024;3760.417135344026;3989.261986532501;4024.71826972115;4041.474608300902;4062.3906414037538;4718;4724.086744868334;4743.905121897543;4772.475027746945;5857.619254748428;5883.529317654663;5927.9407205928255;5986.7904623338845;5989.670568118708;1302.2902518127453;1339.852616212216;1354.5800801751489;1368.6852024034529;1373.8007988841762;902.6234991637674;903.1781079217881;910.9114608043457;924.8057907839294;931.1573026410973;5177.534021938043;5453.291072160594;5478.58910581708;5483.232347471358;5490.368607610176;5117.948820511747;5487.134374312753;5735.340435849931;5815.912761308584;6037.45662890342;5125.666831655931;5208.7916483341;5240.187770895517;5517.5585953123655;5533.916991245134;2804.8176868503483;2826.3781967967016;3172.460681684119;3181.872725091017;3208.2889957985467;5372.415137534436;6184.474319682878;6219.782307619212;6246.750129994749;6353.029356652092;5149.433562308143;5944.464998150187;6108.914997926019;6124.5712800103565;6154.292256390483;5878.030859661975;6147.692615369214;6215.192025036898;6292.2134733158355;6321.557490975588;4732.763361831826;4813.614910807057;5821.359650438533;6013.849653758691;6022.698865056817;4707.741074240949;5187.221916712523;5222.2688823565495;6014.586572155627;6023.698815059317;5072.771725272298;6271.404216599416;6275.650735091977;6296.307406185408;6305.5723546435875;3278.524613930919;3808.9619103809714;3905.453236546829;3933.527976642821;3945.270125026879;5235.821940063607;5624.775008999594;5637.943620563906;5893.292804863444;5936.406359364029;5039.168537191323;5708.172315014383;5828.6502809831545;5830.096335068071;5870.559708022;5772.536478113098;5836.737346819488;5857.50211232033;5876.676782776988;5907.438793314628;4368.475782906072;5312.9734351328;5492.423295554069;5652.434756524314;5711.457906365341;4817.8313759018265;5740.196372507936;5801.216835727291;5801.6519008859605;5813.76744930198;5138.3833939927445;5469.425710300361;5641.633293835887;5648.887022259614;5671.291088613923 10:39:29 INFO - PROCESS | 1654 | |5;yui.html;721.372406006774;752.7214930475725;753.8605358008768;790.7588185603396;846.1749793950898;15092.77360839587;16278.755818662714;16303.75544366834;16984.660306793856;17687.646247075052;16459.34162633496;16473.752893706605;16613.252403641836;16630.417935372265;16647.1676416179;844.8352571248608;847.3644766425181;873.4366333714781;878.8505953987806;878.9208971192592;684.910961574997;698.3993765361785;846.7290467050547;871.860502319629;882.2853488674194;897.7620930453435;897.9910200897973;901.8331608652387;901.8602116671926;902.5848109869468;870.7300736771558;891.4873947480193;892.5715656484923;893.7810236492069;894.9373543851965;651.2738296799035;652.8302641313293;666.2637785247281;668.3951024322995;670.430134385773;15377;15394.846051539453;15443.845561544465;15481.767773483407;15558.688826223412;14253;14364.138151710826;14383;14385.78421323681;14452.49416270436;11911.940440297829;12001.399930003465;12035.578754743541;12057.819132713017;12096.125904363364;3755.34281500739;3761.54861416629;3795.2409518096215;3810.7141964352786;3928.5874983126932;96608;104904.37739056509;111016.4449177757;116534.44262863352;121193;8808.823823523493;9088.591013404413;10463.738406539898;10883.67348979532;11319.886801132048;22334.776652233595;22546.77453225447;22564.774352256594;22627.321180364615;22632.773672263065;6767.932320676829;7865.528068315919;7901.881471777928;7919.8862336784705;8126.593670316461;4420.071784925201;4468.061707041557;4472.888177795581;4484.349769283435;4551.795169217393;3119.298157914451;3650.10572409756;3764.171882185915;3769.264993326275;3794.6774524165203;597.4496182406648;633.3286716080969;643.6878114114651;656.8226578823691;669.6885948034187;577.2409281794427;609.3114780298234;651.3486513486514;663.8008597420851;671.0843074479458;7115.893261600977;8976.685815996409;9313.720588382224;9347.719568412958;9682.515874206261;555.9166125081271;616.3713012727083;617.4905702795212;649.4641920415581;653.4935425045533;3669.7614655047337;4584.312353147055;4630.976845115752;4694.812207511662;4754.381930349033;161215;161988;166952;167065.16467417584;172242;99748;139081.30459347638;139138;140085;142992.28503857413;69506.304936951;81348.1528146512;96277;97154;116167.4491158089;9243.445393276425;9810.75473113178;9901.702948911545;9990.900090998997;9991.600335986479;60475.69762151161;60945.78108437895;61558.69220653868;62004;63378;80189.58009409161;91974.54012729894;92615;92815;95550.04449955412;13031.804522931974;13044.934775326443;13110.475580976654;13190;13419.932900335827;14541.127532348093;14755.704885902007;14795.852041479448;14873.553793386216;15186.772198416811;11974.700632484257;12936.676583085498;13864.168149911038;14215;14551.41794328215;10130.936251693465;11866.228695134789;12813.423395947391;12862.807057893951;13064.464972445994;13305.53430629962;13379.022646006919;13543.322833858465;13796.517121900686;13825.30873456303;9537.952310238405;10864.565417383215;11134.27627204229;11183;11203.551857925591;8469.5341756204;10540;10683.581253998957;11258.38078905668;11621.24461909973 10:39:29 INFO - PROCESS | 1654 | __end_tp_report 10:39:29 INFO - PROCESS | 1654 | __start_cc_report 10:39:29 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,355 10:39:29 INFO - PROCESS | 1654 | __end_cc_report 10:39:29 INFO - PROCESS | 1654 | __startTimestamp1475602769794__endTimestamp 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:39:29 INFO - PROCESS | 1654 | Number of tests: 6 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5714.43 Median:5925.41 stddev:651.43 (11.0%) stddev-sans-first:626.03 10:39:29 INFO - PROCESS | 1654 | Values: 3331.3 3398.7 3405.7 3439.9 3442.6 4701.0 4704.7 5141.5 5588.5 5700.3 5557.8 5611.4 5669.6 5696.8 5739.9 5159.6 5202.9 5247.2 5260.7 5261.4 5208.2 5243.6 5287.8 5291.3 5313.1 5243.1 5273.9 5278.0 5278.1 5300.3 4648.8 4948.9 5253.7 5265.4 5273.8 4254.4 5092.6 5206.5 5207.4 5216.7 5683.0 5695.5 5743.9 5780.2 5819.5 6065.9 6197.6 6221.6 6255.2 6278.7 6103.9 6171.6 6195.1 6230.9 6326.7 5814.8 5842.4 5846.4 5856.6 5875.6 6244.5 6254.4 6285.7 6293.7 6368.1 5964.3 6030.3 6100.7 6151.2 6177.9 6132.1 6195.7 6197.8 6202.5 6261.8 6229.3 6233.7 6235.3 6248.2 6257.6 5988.4 6077.5 6218.9 6257.6 6270.7 4874.6 4880.4 4913.5 4938.2 4953.4 4358.5 4394.8 4409.6 4419.9 4445.3 5621.2 5718.0 5798.8 5809.3 5864.8 6144.5 6193.9 6196.8 6258.3 6318.9 5822.9 5837.9 5862.5 5863.0 5877.7 5308.5 5319.1 5320.6 5322.5 5419.9 6277.4 6295.4 6302.1 6304.3 6370.0 6210.4 6240.0 6245.8 6257.1 6257.6 5481.6 6019.8 6196.9 6293.3 6307.1 6106.3 6199.7 6202.8 6214.8 6240.2 6037.0 6247.4 6256.5 6258.7 6286.2 5947.5 5997.6 6075.8 6157.0 6159.1 6075.2 6095.4 6105.0 6126.1 6146.5 5726.4 5934.8 5935.6 5956.4 5971.9 5855.8 6080.2 6103.2 6113.8 6135.3 5796.7 5858.2 5888.8 5906.5 5925.4 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:64546.53 Median:40947.49 stddev:137017.60 (334.6%) stddev-sans-first:137317.50 10:39:29 INFO - PROCESS | 1654 | Values: 467.6 470.3 496.3 501.6 520.3 39585.0 41414.6 41439.8 43224.8 43242.6 42230.2 42621.4 42630.1 42769.0 42808.0 9653.3 9791.3 9791.8 9844.1 9901.0 9717.7 9817.6 9838.1 9841.8 9847.9 9807.9 9858.4 9863.5 9888.7 9913.5 9838.5 9850.0 9853.9 9859.9 9867.2 8284.3 8388.2 9809.4 9812.9 9836.8 40011.0 40158.6 40203.6 40358.0 40480.4 3287.5 3348.1 3516.5 3821.9 3963.6 30467.5 30883.0 31164.7 31202.8 32016.9 2841.2 3208.0 3249.4 3262.3 3363.1 441957.0 442937.0 447540.0 448190.0 449379.0 54927.0 55193.4 55216.2 55344.4 55383.4 47543.8 57910.0 58034.0 58273.1 58512.0 15710.0 15910.0 15916.4 15954.4 15994.0 12365.8 12375.6 12390.4 12405.3 12440.4 11239.7 14890.9 14989.3 15016.4 15154.5 7028.5 7089.3 7235.7 7243.6 7250.7 824.9 925.7 946.9 963.8 1022.6 24807.4 25179.0 25385.9 25837.6 26550.9 990.6 999.3 1009.4 1048.6 1057.9 12879.7 14812.8 15241.2 15361.0 15535.8 615937.0 620641.0 622079.7 625158.7 625781.9 55896.4 56010.9 56256.4 56315.2 56350.7 1358.9 1367.7 1371.9 1393.9 1394.8 28156.4 28876.9 29456.7 29780.1 29785.7 46562.5 46762.3 46978.0 46995.8 47028.5 386269.0 468096.0 469041.2 469122.0 471908.0 37473.4 37855.4 37894.4 38109.6 38262.6 42650.6 46136.5 46231.0 46380.0 47280.1 38270.4 38550.8 39211.2 39252.4 39326.8 33815.7 38143.6 38248.4 39474.6 39504.4 36202.8 37474.3 38228.0 38389.2 38395.0 30151.1 31473.8 34409.0 34493.8 34670.7 20188.3 21144.4 23815.3 24481.4 26322.5 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33247.60 Median:379.11 stddev:92886.81 (24501.4%) stddev-sans-first:93114.57 10:39:29 INFO - PROCESS | 1654 | Values: 369.1 370.2 376.1 382.1 396.9 17641.7 19154.2 21044.5 21117.5 21274.5 18301.5 18802.0 20177.6 20277.3 20643.9 4888.5 5903.5 5915.0 6128.9 6220.6 6059.2 6185.8 6189.8 6193.3 6211.0 5798.8 6009.6 6053.4 6056.2 6090.1 5476.9 5507.8 5857.5 5916.1 6024.5 5057.2 5187.3 5224.2 5229.5 5229.7 18624.6 23133.8 24852.0 25493.5 25520.7 2183.9 2396.9 3021.4 3062.9 3120.6 15263.5 15402.1 15412.7 15628.3 15710.0 1443.5 1601.5 1807.8 1895.6 1901.1 95672.5 123941.0 124005.4 124249.4 125830.0 7726.5 9503.2 10836.1 10898.7 11039.0 13391.3 13401.6 13442.8 13468.4 13492.2 8367.3 8491.6 8493.1 8517.0 8662.9 8104.6 8580.7 8619.0 8770.3 8801.7 5724.7 6452.9 6476.9 6489.7 6521.2 1854.4 1866.6 1869.7 1870.1 1902.5 881.5 935.1 958.6 994.8 998.9 15829.5 21025.4 22158.6 22174.1 22287.8 844.3 896.5 964.5 991.7 1003.8 7793.5 8589.1 9599.5 10647.7 11261.6 511789.0 516259.0 516438.0 609171.0 614874.0 78350.0 81021.2 109892.0 135779.0 138191.0 957.0 991.4 1004.8 1006.3 1011.3 18813.7 19086.6 19182.6 19184.7 19194.5 36487.8 50244.0 54184.5 56336.4 56513.0 69076.0 82015.9 84233.0 84616.0 85663.6 7445.8 7881.2 7976.5 8092.9 8129.7 11521.2 12283.5 12597.0 12666.7 12972.4 9785.2 10441.9 10730.5 11073.1 11203.5 10696.5 10756.8 11151.3 11192.6 11254.9 8834.5 9738.6 10167.9 11514.9 11638.6 6783.8 7922.9 7952.0 8023.4 8235.4 7935.0 10606.0 10933.2 11091.8 11179.0 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5738.02 Median:4891.03 stddev:3256.53 (66.6%) stddev-sans-first:3250.35 10:39:29 INFO - PROCESS | 1654 | Values: 1535.5 1581.8 1817.5 1913.8 1924.4 6964.4 8349.8 8621.1 9158.9 9228.4 8496.0 8618.5 8779.8 8962.9 9088.0 2330.7 2372.6 2373.4 2381.5 2385.9 2347.2 2364.6 2394.2 2407.6 2410.8 2376.8 2382.8 2389.5 2394.1 2404.6 2337.3 2357.4 2358.7 2370.1 2393.3 2342.1 2354.4 2357.1 2361.6 2362.4 9066.1 9129.6 9185.4 9203.0 9206.0 5262.6 5434.6 5438.6 5443.3 5447.0 3591.9 3694.4 3737.6 3782.7 3818.2 1065.7 1089.5 1099.1 1100.4 1103.2 11749.4 11988.0 12014.8 12035.8 12040.0 4382.9 4489.1 4645.3 4648.5 4660.1 8522.0 9258.8 9326.8 9330.5 9331.2 5252.3 5263.7 5266.6 5275.3 5279.7 4430.2 4580.4 4706.9 4852.6 4929.5 3645.5 3686.9 3772.5 3792.9 3807.0 1156.1 1161.4 1164.4 1169.5 1169.9 813.3 905.1 911.2 913.1 946.5 5691.4 5807.6 5938.0 6012.2 6168.9 380.7 431.9 444.9 906.7 999.6 3585.7 3922.2 3939.6 4016.4 4076.6 10779.0 11042.6 11075.9 11091.1 11273.3 9544.4 10804.4 10919.2 11012.0 11134.1 8212.1 8263.5 8353.3 8364.2 8415.7 4788.9 5904.9 5908.9 5913.0 5927.4 10139.6 10290.4 10295.9 10302.3 10328.6 9771.7 10196.7 10244.1 10423.1 10432.7 6048.9 7035.2 7222.2 7603.0 7758.3 8388.0 8621.5 8669.6 8688.2 8692.4 7593.9 7679.3 7696.5 7791.7 7829.1 7479.3 7561.5 7606.1 7657.0 7771.4 6842.5 6968.7 6973.8 6975.2 6995.1 4997.6 5056.9 5078.7 5090.4 5187.6 4988.4 4996.3 5021.7 5105.2 5170.7 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4649.76 Median:5229.05 stddev:1467.30 (28.1%) stddev-sans-first:1434.58 10:39:29 INFO - PROCESS | 1654 | Values: 296.6 301.2 308.1 309.2 312.7 4208.5 5498.8 5518.5 5604.2 5631.9 5286.9 5454.0 5484.5 5505.0 5506.3 3718.6 4124.4 4161.6 4201.9 4215.2 3893.2 4179.9 4224.6 4233.7 4246.7 3389.7 3410.4 3761.8 4149.3 4164.8 3382.4 4151.5 4153.3 4155.6 4172.3 3572.8 3592.0 3604.9 3618.4 3623.6 4986.8 5329.3 5375.0 5401.8 5475.4 4713.8 4761.0 4770.4 4790.0 4801.2 4297.2 4933.6 5000.9 5520.0 5584.6 3436.7 3557.3 3783.5 3821.5 3949.2 5099.5 5589.6 5764.3 6125.5 6240.2 3230.5 3329.5 3535.1 3729.5 4000.8 3498.7 3760.4 3989.3 4024.7 4041.5 4062.4 4718.0 4724.1 4743.9 4772.5 5857.6 5883.5 5927.9 5986.8 5989.7 1302.3 1339.9 1354.6 1368.7 1373.8 902.6 903.2 910.9 924.8 931.2 5177.5 5453.3 5478.6 5483.2 5490.4 5117.9 5487.1 5735.3 5815.9 6037.5 5125.7 5208.8 5240.2 5517.6 5533.9 2804.8 2826.4 3172.5 3181.9 3208.3 5372.4 6184.5 6219.8 6246.8 6353.0 5149.4 5944.5 6108.9 6124.6 6154.3 5878.0 6147.7 6215.2 6292.2 6321.6 4732.8 4813.6 5821.4 6013.8 6022.7 4707.7 5187.2 5222.3 6014.6 6023.7 5072.8 6271.4 6275.7 6296.3 6305.6 3278.5 3809.0 3905.5 3933.5 3945.3 5235.8 5624.8 5637.9 5893.3 5936.4 5039.2 5708.2 5828.7 5830.1 5870.6 5772.5 5836.7 5857.5 5876.7 5907.4 4368.5 5313.0 5492.4 5652.4 5711.5 4817.8 5740.2 5801.2 5801.7 5813.8 5138.4 5469.4 5641.6 5648.9 5671.3 10:39:29 INFO - PROCESS | 1654 | 10:39:29 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24948.16 Median:4174.33 stddev:40606.92 (972.8%) stddev-sans-first:40680.09 10:39:29 INFO - PROCESS | 1654 | Values: 721.4 752.7 753.9 790.8 846.2 15092.8 16278.8 16303.8 16984.7 17687.6 16459.3 16473.8 16613.3 16630.4 16647.2 844.8 847.4 873.4 878.9 878.9 684.9 698.4 846.7 871.9 882.3 897.8 898.0 901.8 901.9 902.6 870.7 891.5 892.6 893.8 894.9 651.3 652.8 666.3 668.4 670.4 15377.0 15394.8 15443.8 15481.8 15558.7 14253.0 14364.1 14383.0 14385.8 14452.5 11911.9 12001.4 12035.6 12057.8 12096.1 3755.3 3761.5 3795.2 3810.7 3928.6 96608.0 104904.4 111016.4 116534.4 121193.0 8808.8 9088.6 10463.7 10883.7 11319.9 22334.8 22546.8 22564.8 22627.3 22632.8 6767.9 7865.5 7901.9 7919.9 8126.6 4420.1 4468.1 4472.9 4484.3 4551.8 3119.3 3650.1 3764.2 3769.3 3794.7 597.4 633.3 643.7 656.8 669.7 577.2 609.3 651.3 663.8 671.1 7115.9 8976.7 9313.7 9347.7 9682.5 555.9 616.4 617.5 649.5 653.5 3669.8 4584.3 4631.0 4694.8 4754.4 161215.0 161988.0 166952.0 167065.2 172242.0 99748.0 139081.3 139138.0 140085.0 142992.3 69506.3 81348.2 96277.0 97154.0 116167.4 9243.4 9810.8 9901.7 9990.9 9991.6 60475.7 60945.8 61558.7 62004.0 63378.0 80189.6 91974.5 92615.0 92815.0 95550.0 13031.8 13044.9 13110.5 13190.0 13419.9 14541.1 14755.7 14795.9 14873.6 15186.8 11974.7 12936.7 13864.2 14215.0 14551.4 10130.9 11866.2 12813.4 12862.8 13064.5 13305.5 13379.0 13543.3 13796.5 13825.3 9538.0 10864.6 11134.3 11183.0 11203.6 8469.5 10540.0 10683.6 11258.4 11621.2 10:39:29 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:39:29 INFO - PROCESS | 1654 | 10:39:30 INFO - TEST-INFO | 1654: exit 0 10:39:30 INFO - TEST-OK | dromaeo_css | took 1115604ms 10:39:30 INFO - TEST-START | kraken 10:39:30 INFO - operating with platform_type : mac_ 10:39:30 INFO - Initialising browser for kraken test... 10:39:30 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpTYbHyF/profile http://localhost:49275/getInfo.html) 10:39:33 INFO - PROCESS | 1664 | 2016-10-04 10:39:33.180 plugin-container[1665:10160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.tsm.portname' 10:39:33 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:33 INFO - PROCESS | 1664 | 2016-10-04 10:39:33.184 plugin-container[1665:10160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 10:39:33 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:33 INFO - PROCESS | 1664 | 2016-10-04 10:39:33.184 plugin-container[1665:10160] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:39:33 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 10:39:33 INFO - PROCESS | 1664 | colorDepth:24 10:39:33 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 10:39:33 INFO - PROCESS | 1664 | __metrics 10:39:33 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 10:39:33 INFO - TEST-INFO | 1664: exit 0 10:39:33 INFO - Browser initialized. 10:39:33 INFO - Running cycle 1/1 for kraken test... 10:39:34 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpTYbHyF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:36 INFO - PROCESS | 1666 | 2016-10-04 10:39:36.285 plugin-container[1667:10272] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.tsm.portname' 10:39:36 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:36 INFO - PROCESS | 1666 | 2016-10-04 10:39:36.288 plugin-container[1667:10272] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 10:39:36 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:36 INFO - PROCESS | 1666 | 2016-10-04 10:39:36.288 plugin-container[1667:10272] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:39:45 INFO - PROCESS | 1666 | RSS: Main: 240410624 10:39:45 INFO - PROCESS | 1666 | 10:39:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:39:49 INFO - PROCESS | 1666 | RSS: Main: 253804544 10:39:49 INFO - PROCESS | 1666 | 10:39:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:39:53 INFO - PROCESS | 1666 | RSS: Main: 262164480 10:39:53 INFO - PROCESS | 1666 | 10:39:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:39:57 INFO - PROCESS | 1666 | RSS: Main: 262459392 10:39:57 INFO - PROCESS | 1666 | 10:39:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:39:59 INFO - PROCESS | 1666 | RSS: Main: 263245824 10:39:59 INFO - PROCESS | 1666 | 10:40:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:40:02 INFO - PROCESS | 1666 | RSS: Main: 264851456 10:40:02 INFO - PROCESS | 1666 | 10:40:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:05 INFO - PROCESS | 1666 | RSS: Main: 265641984 10:40:05 INFO - PROCESS | 1666 | 10:40:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:09 INFO - PROCESS | 1666 | RSS: Main: 265703424 10:40:09 INFO - PROCESS | 1666 | 10:40:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:40:13 INFO - PROCESS | 1666 | RSS: Main: 265908224 10:40:13 INFO - PROCESS | 1666 | 10:40:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:15 INFO - PROCESS | 1666 | RSS: Main: 265629696 10:40:15 INFO - PROCESS | 1666 | 10:40:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:18 INFO - PROCESS | 1666 | RSS: Main: 265711616 10:40:18 INFO - PROCESS | 1666 | 10:40:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:20 INFO - PROCESS | 1666 | RSS: Main: 265687040 10:40:20 INFO - PROCESS | 1666 | 10:40:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:24 INFO - PROCESS | 1666 | RSS: Main: 266104832 10:40:24 INFO - PROCESS | 1666 | 10:40:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:28 INFO - PROCESS | 1666 | RSS: Main: 266309632 10:40:28 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:40:30 INFO - PROCESS | 1666 | RSS: Main: 267931648 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | __start_tp_report 10:40:30 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 10:40:30 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 10:40:30 INFO - PROCESS | 1666 | |i|pagename|runs| 10:40:30 INFO - PROCESS | 1666 | |0;ai-astar;112;116;118;117;115;116;119;117;115;117 10:40:30 INFO - PROCESS | 1666 | |1;audio-beat-detection;149;123;147;188;116;115;114;115;155;100 10:40:30 INFO - PROCESS | 1666 | |2;audio-dft;197;193;224;272;236;198;200;197;195;195 10:40:30 INFO - PROCESS | 1666 | |3;audio-fft;87;57;60;84;60;62;59;58;79;78 10:40:30 INFO - PROCESS | 1666 | |4;audio-oscillator;88;70;85;73;71;71;87;70;71;91 10:40:30 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;92;85;80;131;131;85;89;85;125;87 10:40:30 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;95;94;95;109;95;107;90;149;152 10:40:30 INFO - PROCESS | 1666 | |7;imaging-desaturate;141;153;79;146;145;90;120;91;151;146 10:40:30 INFO - PROCESS | 1666 | |8;json-parse-financial;57;59;56;56;57;59;57;60;57;58 10:40:30 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;68;71;71;84;105;107;99;78;69;70 10:40:30 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;93;91;88;93;91;86;87;88;89;90 10:40:30 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;164;161;161;147;149;163;162;147;206;183 10:40:30 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;235;239;233;175;180;171;179;224;239;196 10:40:30 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;70;81;71;71;68;71;67;83;88 10:40:30 INFO - PROCESS | 1666 | __end_tp_report 10:40:30 INFO - PROCESS | 1666 | __start_cc_report 10:40:30 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,933 10:40:30 INFO - PROCESS | 1666 | __end_cc_report 10:40:30 INFO - PROCESS | 1666 | __startTimestamp1475602830585__endTimestamp 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | ------- Summary: start ------- 10:40:30 INFO - PROCESS | 1666 | Number of tests: 14 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.00 stddev:1.93 (1.7%) stddev-sans-first:1.32 10:40:30 INFO - PROCESS | 1666 | Values: 112.0 116.0 118.0 117.0 115.0 116.0 119.0 117.0 115.0 117.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:135.00 stddev:26.75 (19.8%) stddev-sans-first:27.68 10:40:30 INFO - PROCESS | 1666 | Values: 149.0 123.0 147.0 188.0 116.0 115.0 114.0 115.0 155.0 100.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:210.70 Median:199.00 stddev:25.89 (13.0%) stddev-sans-first:26.98 10:40:30 INFO - PROCESS | 1666 | Values: 197.0 193.0 224.0 272.0 236.0 198.0 200.0 197.0 195.0 195.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:68.40 Median:70.00 stddev:12.03 (17.2%) stddev-sans-first:10.71 10:40:30 INFO - PROCESS | 1666 | Values: 87.0 57.0 60.0 84.0 60.0 62.0 59.0 58.0 79.0 78.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:8.81 (11.1%) stddev-sans-first:8.52 10:40:30 INFO - PROCESS | 1666 | Values: 88.0 70.0 85.0 73.0 71.0 71.0 87.0 70.0 71.0 91.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:85.00 stddev:20.99 (24.7%) stddev-sans-first:22.11 10:40:30 INFO - PROCESS | 1666 | Values: 92.0 85.0 80.0 131.0 131.0 85.0 89.0 85.0 125.0 87.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:94.00 stddev:23.19 (24.7%) stddev-sans-first:24.04 10:40:30 INFO - PROCESS | 1666 | Values: 94.0 95.0 94.0 95.0 109.0 95.0 107.0 90.0 149.0 152.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:126.20 Median:152.00 stddev:28.88 (19.0%) stddev-sans-first:30.13 10:40:30 INFO - PROCESS | 1666 | Values: 141.0 153.0 79.0 146.0 145.0 90.0 120.0 91.0 151.0 146.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:1.35 (2.3%) stddev-sans-first:1.41 10:40:30 INFO - PROCESS | 1666 | Values: 57.0 59.0 56.0 56.0 57.0 59.0 57.0 60.0 57.0 58.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:82.20 Median:71.00 stddev:15.68 (22.1%) stddev-sans-first:15.77 10:40:30 INFO - PROCESS | 1666 | Values: 68.0 71.0 71.0 84.0 105.0 107.0 99.0 78.0 69.0 70.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.50 stddev:2.41 (2.7%) stddev-sans-first:2.22 10:40:30 INFO - PROCESS | 1666 | Values: 93.0 91.0 88.0 93.0 91.0 86.0 87.0 88.0 89.0 90.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:164.30 Median:162.50 stddev:18.10 (11.1%) stddev-sans-first:19.20 10:40:30 INFO - PROCESS | 1666 | Values: 164.0 161.0 161.0 147.0 149.0 163.0 162.0 147.0 206.0 183.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.10 Median:228.50 stddev:29.35 (12.8%) stddev-sans-first:29.34 10:40:30 INFO - PROCESS | 1666 | Values: 235.0 239.0 233.0 175.0 180.0 171.0 179.0 224.0 239.0 196.0 10:40:30 INFO - PROCESS | 1666 | 10:40:30 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:71.00 stddev:7.29 (10.3%) stddev-sans-first:7.52 10:40:30 INFO - PROCESS | 1666 | Values: 69.0 70.0 81.0 71.0 71.0 68.0 71.0 67.0 83.0 88.0 10:40:30 INFO - PROCESS | 1666 | -------- Summary: end -------- 10:40:30 INFO - PROCESS | 1666 | 10:40:31 INFO - TEST-INFO | 1666: exit 0 10:40:31 INFO - TEST-OK | kraken | took 61140ms 10:40:31 INFO - SUITE-END | took 1176s 10:40:31 INFO - Completed test suite (00:19:36) 10:40:31 INFO - javascript benchmark 10:40:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7341.9930119621085, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.3004269103485, 3398.6771256730603, 3405.6764607362306, 3439.896803095907, 3442.569678790151, 4701.0, 4704.670673052888, 5141.537261646451, 5588.497035266825, 5700.287464066992, 5557.833265002047, 5611.410801865806, 5669.603127781057, 5696.829095127153, 5739.913901291484, 5159.587233021359, 5202.869928251786, 5247.239150323201, 5260.710660913648, 5261.421243663194, 5208.244804503344, 5243.554297884684, 5287.788488460457, 5291.31212942317, 5313.068329436209, 5243.082460569404, 5273.920891186635, 5277.973610131944, 5278.102722537159, 5300.337457817773, 4648.768076459741, 4948.90102197954, 5253.737313134367, 5265.447128051557, 5273.789048438058, 4254.404383386297, 5092.592592592584, 5206.5314121729225, 5207.427183009904, 5216.686998780086, 5683.0, 5695.45893140151, 5743.856403589902, 5780.1907732917425, 5819.505342045931, 6065.878682426327, 6197.566170368076, 6221.595596286272, 6255.155554000243, 6278.686065696743, 6103.883205879467, 6171.567990240685, 6195.101710252034, 6230.937690623126, 6326.65203413812, 5814.767409303623, 5842.444967728059, 5846.386129456431, 5856.648601083949, 5875.559333050039, 6244.469220116341, 6254.405831446005, 6285.685715714196, 6293.716782744787, 6368.140301059298, 5964.254468191476, 6030.33666296707, 6100.664463454553, 6151.169592105098, 6177.907331389943, 6132.141500189977, 6195.690215489208, 6197.783077592263, 6202.534809889276, 6261.812145635638, 6229.283632382243, 6233.657148856766, 6235.251769787564, 6248.187735594343, 6257.624542527463, 5988.371221021817, 6077.483413909778, 6218.9378106219265, 6257.56197066201, 6270.717817698214, 4874.561289483892, 4880.438749543777, 4913.484084171182, 4938.209886418156, 4953.380827396575, 4358.455193100863, 4394.802233899482, 4409.625181859577, 4419.9337009944875, 4445.333200020023, 5621.15682647598, 5718.027935251017, 5798.768049277982, 5809.331926828384, 5864.794732184353, 6144.494598823317, 6193.876122477525, 6196.81409557714, 6258.280297765724, 6318.905216421757, 5822.94177058224, 5837.9124313135335, 5862.472377486047, 5862.970685146547, 5877.676727780014, 5308.522232999048, 5319.1223448131395, 5320.600954928396, 5322.547583455373, 5419.918701219485, 6277.435030847246, 6295.433410993032, 6302.117703521419, 6304.306526282106, 6370.044493326038, 6210.4100110489435, 6239.968800156152, 6245.781397651061, 6257.1240026395435, 6257.5932564383165, 5481.561475082063, 6019.789307374221, 6196.938030619636, 6293.307736148929, 6307.148534947723, 6106.339326076981, 6199.690015499117, 6202.782902598387, 6214.844628884314, 6240.219972503437, 6037.0, 6247.437730604267, 6256.468200202851, 6258.687065646791, 6286.214223222098, 5947.494462970523, 5997.610155339889, 6075.787347442818, 6157.0, 6159.106929495288, 6075.1798507200965, 6095.420935011167, 6105.004892149239, 6126.142340072304, 6146.494108943338, 5726.4273572642405, 5934.821955341346, 5935.643861368159, 5956.374580668968, 5971.940280597312, 5855.824325270249, 6080.179175811209, 6103.23709536308, 6113.816585502442, 6135.263768347827, 5796.739146738322, 5858.179854820243, 5888.793892213752, 5906.527477801851, 5925.40745925404], "value": 5672.767823278234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.5978658353817, 470.34387030093023, 496.3274762696546, 501.64383287865616, 520.287206527058, 39585.0, 41414.58585414141, 41439.79280103603, 43224.783876080655, 43242.56757432433, 42230.15539689212, 42621.36067958975, 42630.14739705204, 42769.0, 42808.0, 9653.276004299689, 9791.314607977445, 9791.804163916717, 9844.114029737322, 9900.950495247514, 9717.659881904136, 9817.607295708163, 9838.06538378853, 9841.753956151082, 9847.882705218377, 9807.852882206773, 9858.359206651581, 9863.457509836991, 9888.653897113603, 9913.504324783731, 9838.458884761336, 9850.014998500164, 9853.90146098537, 9859.852102218472, 9867.210623150131, 8284.25441710243, 8388.203120703523, 9809.411435313905, 9812.90187098134, 9836.803263934682, 40011.0, 40158.59841401607, 40203.59796402057, 40358.0, 40480.39279410811, 3287.474004159323, 3348.11275012122, 3516.507688923553, 3821.9426708599394, 3963.6432721055244, 30467.542986854995, 30883.0, 31164.688353116406, 31202.843985779928, 32016.879409220564, 2841.1618572520947, 3207.951880721791, 3249.382617302705, 3262.298605799741, 3363.0751543325537, 441957.0, 442937.0, 447539.99999999674, 448190.0, 449379.0, 54927.0, 55193.44806551883, 55216.17175742367, 55344.44655553393, 55383.44616553863, 47543.76228118837, 57910.0, 58034.0, 58273.12590311149, 58512.0, 15710.0, 15910.0, 15916.442924497587, 15954.361825527081, 15994.0, 12365.75268494643, 12375.566855160027, 12390.380480975915, 12405.317707526172, 12440.377981100908, 11239.719007024889, 14890.925545372203, 14989.325480353411, 15016.399344026335, 15154.545363639108, 7028.543144695578, 7089.291070892869, 7235.710571577078, 7243.637818109073, 7250.694874922459, 824.9340052795762, 925.7222833150028, 946.9053094690476, 963.7783309838776, 1022.5654097008742, 24807.379815504395, 25179.0, 25385.873070634898, 25837.612435813477, 26550.937962481283, 990.6285143071349, 999.3404353126901, 1009.4044513736784, 1048.5700862646431, 1057.947581691516, 12879.678008049686, 14812.777808332883, 15241.23793810305, 15361.001534900195, 15535.84464155344, 615937.0, 620641.0, 622079.6688049682, 625158.7357137789, 625781.8710906416, 55896.44103558912, 56010.879782404125, 56256.43743562512, 56315.15527267094, 56350.718246408505, 1358.9456421743216, 1367.7196174784146, 1371.8559551246976, 1393.9303034848217, 1394.7768357063023, 28156.43687126287, 28876.855615721786, 29456.705432945397, 29780.064295820703, 29785.702142978294, 46562.53437465582, 46762.29856552086, 46978.0, 46995.76502117467, 47028.529714703785, 386269.0, 468096.0, 469041.23835046217, 469122.0, 471908.0, 37473.437898432094, 37855.43216851805, 37894.431583525715, 38109.618903811715, 38262.6173738259, 42650.61195389531, 46136.53863461457, 46231.0, 46380.0, 47280.054398912514, 38270.42594361031, 38550.807245964716, 39211.2157756849, 39252.41121383238, 39326.82019539304, 33815.66184338224, 38143.61856381401, 38248.426273605364, 39474.60525394824, 39504.407433887936, 36202.81898590596, 37474.25051499009, 38228.0, 38389.232215354976, 38395.040123996005, 30151.09546713602, 31473.842630787614, 34409.0, 34493.82753086218, 34670.65329346674, 20188.293409730588, 21144.365669029954, 23815.285541433786, 24481.38796530101, 26322.473550529267], "value": 19423.94134685366, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.09387453800923, 370.1763576043304, 376.1386425086552, 382.0791891541386, 396.9263143197651, 17641.735373969368, 19154.233830646775, 21044.473888152803, 21117.47206319841, 21274.46813829655, 18301.542461438472, 18801.96589187594, 20177.596448071028, 20277.290294839684, 20643.896780516116, 4888.486708895568, 5903.4686878180955, 5915.0240210365455, 6128.938710612903, 6220.564560480768, 6059.242594675666, 6185.783497577586, 6189.81430557084, 6193.256809182904, 6211.0372892201785, 5798.768049278024, 6009.57932944694, 6053.424924632153, 6056.182415373934, 6090.086487026959, 5476.890462190754, 5507.788286576947, 5857.506335884323, 5916.083007133858, 6024.457798798107, 5057.216131499624, 5187.299714538527, 5224.164133738622, 5229.5293423591675, 5229.660072095302, 18624.62750744991, 23133.76866231333, 24852.0, 25493.49013019748, 25520.74479255202, 2183.9017244224046, 2396.9041238350446, 3021.4108248891475, 3062.892798752055, 3120.5787218725636, 15263.542093737206, 15402.075875447508, 15412.691746165126, 15628.296726647215, 15710.0, 1443.501991812823, 1601.4795191562673, 1807.8011418743927, 1895.6398284326071, 1901.115981068796, 95672.52163739207, 123941.0, 124005.37997309955, 124249.37875310566, 125830.0, 7726.536407815436, 9503.239740820718, 10836.133109351233, 10898.727531811768, 11039.006489415831, 13391.263480508665, 13401.597952061455, 13442.798358024638, 13468.393922273326, 13492.190468571916, 8367.288780453608, 8491.575421229038, 8493.108223636553, 8516.957415213008, 8662.87005694915, 8104.554249516215, 8580.742577722678, 8619.0, 8770.25452836516, 8801.691940782042, 5724.713764311851, 6452.870942581122, 6476.870462590816, 6489.740410383626, 6521.152250207538, 1854.378783107647, 1866.5893503429224, 1869.6728072587243, 1870.111696943968, 1902.4768188748205, 881.5019513974712, 935.0696057422907, 958.6309270930742, 994.7712026234007, 998.9250806189565, 15829.525114246591, 21025.369238922856, 22158.556828863333, 22174.1130354784, 22287.777122228894, 844.3118858130604, 896.5338024227365, 964.5273815830193, 991.6925753016588, 1003.7540802503287, 7793.532388056735, 8589.110395712933, 9599.47202903847, 10647.73380665474, 11261.605843795593, 511789.0, 516259.0, 516438.0, 609171.0, 614874.0, 78350.0, 81021.18978810136, 109892.0, 135779.0, 138191.0, 957.0, 991.4249735153738, 1004.8442491413842, 1006.33079002464, 1011.3123076308034, 18813.717794232824, 19086.618267634847, 19182.616347673247, 19184.71222931685, 19194.520136996685, 36487.81756091202, 50244.0, 54184.45815541794, 56336.43663563476, 56513.0, 69076.0, 82015.94960126045, 84233.0, 84616.0, 85663.57168214368, 7445.813854653677, 7881.226723987286, 7976.521408715495, 8092.878606820785, 8129.715459958873, 11521.193516453768, 12283.508659653513, 12597.0, 12666.683332916751, 12972.351382430652, 9785.217182625514, 10441.947790261, 10730.463476825971, 11073.114150868072, 11203.495842686936, 10696.518656660624, 10756.83864742045, 11151.330920144817, 11192.552297908318, 11254.94372528132, 8834.469931804113, 9738.61045558198, 10167.898321016695, 11514.88485115138, 11638.592649257236, 6783.796486105425, 7922.851186578029, 7952.0, 8023.398245131755, 8235.423520353517, 7934.960325198337, 10606.0, 10933.234673572773, 11091.778164436828, 11179.0], "value": 9543.083150777617, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.5163123616064, 1581.7864588280581, 1817.518357635227, 1913.8181872722087, 1924.3938159479762, 6964.442844572434, 8349.791255218623, 8621.051684314729, 9158.86261706074, 9228.400153989995, 8496.02295735989, 8618.525981071038, 8779.824403511926, 8962.86555701665, 9088.0, 2330.720313562375, 2372.596658568039, 2373.4066483379156, 2381.4641705616273, 2385.940351491209, 2347.2371479269223, 2364.5507353602848, 2394.174009966559, 2407.614781634939, 2410.819188560856, 2376.8455050421753, 2382.797462215714, 2389.474315650554, 2394.078279862248, 2404.6272827711646, 2337.2637619149914, 2357.422431504284, 2358.7051618547766, 2370.12305446984, 2393.2820153953744, 2342.121704360865, 2354.3878591566145, 2357.104300365867, 2361.6221404575185, 2362.444825466014, 9066.093390660948, 9129.589168487368, 9185.402948808372, 9202.953985230099, 9206.0, 5262.631615786897, 5434.619576629638, 5438.564914806806, 5443.292371991655, 5446.992306423324, 3591.94612080819, 3694.371956767356, 3737.588865224823, 3782.678472329855, 3818.16000479894, 1065.7016035510069, 1089.477051015517, 1099.1371773158032, 1100.4442756407964, 1103.2222283290262, 11749.412529373583, 11988.040956723442, 12014.837329280814, 12035.759284814343, 12040.036797056215, 4382.9342559861625, 4489.079738653568, 4645.349651048856, 4648.48866624671, 4660.067986402665, 8522.0, 9258.768530786649, 9326.766830829283, 9330.486823224788, 9331.16019558243, 5252.317198764136, 5263.6578622389425, 5266.631335806534, 5275.261463395128, 5279.656822306538, 4430.180416622935, 4580.404547408815, 4706.882327941828, 4852.587530059914, 4929.482404347492, 3645.4714066460215, 3686.9078273043388, 3772.5284339457567, 3792.867249646304, 3807.010177353908, 1156.1213477756796, 1161.35088174792, 1164.4119719541577, 1169.5380324771668, 1169.906407487399, 813.2639960835453, 905.0542183418344, 911.1571796088591, 913.1098625844571, 946.4605175050156, 5691.430856914359, 5807.622504537191, 5938.049912014057, 6012.218411606463, 6168.8766224675255, 380.7449009163867, 431.8574870292796, 444.8910017045844, 906.6509393883402, 999.5651891427252, 3585.748997570145, 3922.235164143022, 3939.625735555118, 4016.3774614934146, 4076.5515793262716, 10779.0, 11042.613508527164, 11075.944620276847, 11091.057260132819, 11273.323600583992, 9544.42733435996, 10804.351738895533, 10919.181061420426, 11011.953864382871, 11134.10927125828, 8212.137725538852, 8263.47963871025, 8353.289970352587, 8364.163583641588, 8415.663373464993, 4788.928166077442, 5904.881902361843, 5908.940910591011, 5913.024350982212, 5927.407259274038, 10139.64511242103, 10290.3825770454, 10295.897041029793, 10302.278840481094, 10328.586856525955, 9771.706848794547, 10196.745081373025, 10244.129249013917, 10423.123837708981, 10432.68701938943, 6048.879022419615, 7035.155781306276, 7222.169450513048, 7602.961985190039, 7758.340541054074, 8388.035375931846, 8621.525816079926, 8669.566521673765, 8688.218060374596, 8692.391532592657, 7593.8860917087395, 7679.308862202429, 7696.538207707556, 7791.688332466638, 7829.138794732461, 7479.289467500579, 7561.546307221584, 7606.125295590857, 7657.0, 7771.378289736695, 6842.521023528305, 6968.7212511501, 6973.790786276217, 6975.162980442379, 6995.090638217, 4997.550220480174, 5056.873578160576, 5078.669886457368, 5090.41460232078, 5187.610929180402, 4988.401391833003, 4996.300517927566, 5021.6735912165595, 5105.157648987875, 5170.663906846043], "value": 4560.744757774131, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.6499530553946, 301.21683622581287, 308.091131163069, 309.1883805011844, 312.7060563070713, 4208.494980602324, 5498.835034948948, 5518.503334699876, 5604.159376093588, 5631.887362252753, 5286.920696189561, 5454.0, 5484.533814625752, 5504.972475137629, 5506.339239291281, 3718.5723641781137, 4124.401961715549, 4161.646260067898, 4201.936970945439, 4215.241256573816, 3893.240818040483, 4179.853705120321, 4224.577542245782, 4233.661307095425, 4246.683528106281, 3389.7457690673173, 3410.369081719877, 3761.849526018956, 4149.340263894452, 4164.7709375984305, 3382.374260761767, 4151.46031015969, 4153.252414565377, 4155.563665815076, 4172.329708986251, 3572.83922223498, 3591.982040089783, 3604.8644676926683, 3618.3667858124722, 3623.61951995042, 4986.825461108844, 5329.267987903947, 5374.984380857179, 5401.810936617238, 5475.397706252309, 4713.835015774432, 4760.976195119037, 4770.356001939763, 4790.0, 4801.231802911518, 4297.162053399588, 4933.629977751683, 5000.899802043545, 5520.0, 5584.6038490377405, 3436.742244331685, 3557.3418917500344, 3783.5270591176104, 3821.541415030214, 3949.150932549516, 5099.526729536172, 5589.608727389084, 5764.250647415809, 6125.540584456139, 6240.188775459206, 3230.5154226866157, 3329.4839299908554, 3535.0910508325214, 3729.533808273966, 4000.8199631016673, 3498.7201023918024, 3760.417135344026, 3989.261986532501, 4024.71826972115, 4041.474608300902, 4062.3906414037538, 4718.0, 4724.086744868334, 4743.905121897543, 4772.475027746945, 5857.619254748428, 5883.529317654663, 5927.9407205928255, 5986.7904623338845, 5989.670568118708, 1302.2902518127453, 1339.852616212216, 1354.5800801751489, 1368.6852024034529, 1373.8007988841762, 902.6234991637674, 903.1781079217881, 910.9114608043457, 924.8057907839294, 931.1573026410973, 5177.534021938043, 5453.291072160594, 5478.58910581708, 5483.232347471358, 5490.368607610176, 5117.948820511747, 5487.134374312753, 5735.340435849931, 5815.912761308584, 6037.45662890342, 5125.666831655931, 5208.7916483341, 5240.187770895517, 5517.5585953123655, 5533.916991245134, 2804.8176868503483, 2826.3781967967016, 3172.460681684119, 3181.872725091017, 3208.2889957985467, 5372.415137534436, 6184.474319682878, 6219.782307619212, 6246.750129994749, 6353.029356652092, 5149.433562308143, 5944.464998150187, 6108.914997926019, 6124.5712800103565, 6154.292256390483, 5878.030859661975, 6147.692615369214, 6215.192025036898, 6292.2134733158355, 6321.557490975588, 4732.763361831826, 4813.614910807057, 5821.359650438533, 6013.849653758691, 6022.698865056817, 4707.741074240949, 5187.221916712523, 5222.2688823565495, 6014.586572155627, 6023.698815059317, 5072.771725272298, 6271.404216599416, 6275.650735091977, 6296.307406185408, 6305.5723546435875, 3278.524613930919, 3808.9619103809714, 3905.453236546829, 3933.527976642821, 3945.270125026879, 5235.821940063607, 5624.775008999594, 5637.943620563906, 5893.292804863444, 5936.406359364029, 5039.168537191323, 5708.172315014383, 5828.6502809831545, 5830.096335068071, 5870.559708022, 5772.536478113098, 5836.737346819488, 5857.50211232033, 5876.676782776988, 5907.438793314628, 4368.475782906072, 5312.9734351328, 5492.423295554069, 5652.434756524314, 5711.457906365341, 4817.8313759018265, 5740.196372507936, 5801.216835727291, 5801.6519008859605, 5813.76744930198, 5138.3833939927445, 5469.425710300361, 5641.633293835887, 5648.887022259614, 5671.291088613923], "value": 4183.095911951014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.372406006774, 752.7214930475725, 753.8605358008768, 790.7588185603396, 846.1749793950898, 15092.77360839587, 16278.755818662714, 16303.75544366834, 16984.660306793856, 17687.646247075052, 16459.34162633496, 16473.752893706605, 16613.252403641836, 16630.417935372265, 16647.1676416179, 844.8352571248608, 847.3644766425181, 873.4366333714781, 878.8505953987806, 878.9208971192592, 684.910961574997, 698.3993765361785, 846.7290467050547, 871.860502319629, 882.2853488674194, 897.7620930453435, 897.9910200897973, 901.8331608652387, 901.8602116671926, 902.5848109869468, 870.7300736771558, 891.4873947480193, 892.5715656484923, 893.7810236492069, 894.9373543851965, 651.2738296799035, 652.8302641313293, 666.2637785247281, 668.3951024322995, 670.430134385773, 15377.0, 15394.846051539453, 15443.845561544465, 15481.767773483407, 15558.688826223412, 14253.0, 14364.138151710826, 14383.0, 14385.78421323681, 14452.49416270436, 11911.940440297829, 12001.399930003465, 12035.578754743541, 12057.819132713017, 12096.125904363364, 3755.34281500739, 3761.54861416629, 3795.2409518096215, 3810.7141964352786, 3928.5874983126932, 96608.0, 104904.37739056509, 111016.4449177757, 116534.44262863352, 121193.0, 8808.823823523493, 9088.591013404413, 10463.738406539898, 10883.67348979532, 11319.886801132048, 22334.776652233595, 22546.77453225447, 22564.774352256594, 22627.321180364615, 22632.773672263065, 6767.932320676829, 7865.528068315919, 7901.881471777928, 7919.8862336784705, 8126.593670316461, 4420.071784925201, 4468.061707041557, 4472.888177795581, 4484.349769283435, 4551.795169217393, 3119.298157914451, 3650.10572409756, 3764.171882185915, 3769.264993326275, 3794.6774524165203, 597.4496182406648, 633.3286716080969, 643.6878114114651, 656.8226578823691, 669.6885948034187, 577.2409281794427, 609.3114780298234, 651.3486513486514, 663.8008597420851, 671.0843074479458, 7115.893261600977, 8976.685815996409, 9313.720588382224, 9347.719568412958, 9682.515874206261, 555.9166125081271, 616.3713012727083, 617.4905702795212, 649.4641920415581, 653.4935425045533, 3669.7614655047337, 4584.312353147055, 4630.976845115752, 4694.812207511662, 4754.381930349033, 161215.0, 161988.0, 166952.0, 167065.16467417584, 172242.0, 99748.0, 139081.30459347638, 139138.0, 140085.0, 142992.28503857413, 69506.304936951, 81348.1528146512, 96277.0, 97154.0, 116167.4491158089, 9243.445393276425, 9810.75473113178, 9901.702948911545, 9990.900090998997, 9991.600335986479, 60475.69762151161, 60945.78108437895, 61558.69220653868, 62004.0, 63378.0, 80189.58009409161, 91974.54012729894, 92615.0, 92815.0, 95550.04449955412, 13031.804522931974, 13044.934775326443, 13110.475580976654, 13190.0, 13419.932900335827, 14541.127532348093, 14755.704885902007, 14795.852041479448, 14873.553793386216, 15186.772198416811, 11974.700632484257, 12936.676583085498, 13864.168149911038, 14215.0, 14551.41794328215, 10130.936251693465, 11866.228695134789, 12813.423395947391, 12862.807057893951, 13064.464972445994, 13305.53430629962, 13379.022646006919, 13543.322833858465, 13796.517121900686, 13825.30873456303, 9537.952310238405, 10864.565417383215, 11134.27627204229, 11183.0, 11203.551857925591, 8469.5341756204, 10540.0, 10683.581253998957, 11258.38078905668, 11621.24461909973], "value": 7807.014046852262, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 118.0, 117.0, 115.0, 116.0, 119.0, 117.0, 115.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 123.0, 147.0, 188.0, 116.0, 115.0, 114.0, 115.0, 155.0, 100.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 193.0, 224.0, 272.0, 236.0, 198.0, 200.0, 197.0, 195.0, 195.0], "value": 210.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 57.0, 60.0, 84.0, 60.0, 62.0, 59.0, 58.0, 79.0, 78.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 85.0, 73.0, 71.0, 71.0, 87.0, 70.0, 71.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 80.0, 131.0, 131.0, 85.0, 89.0, 85.0, 125.0, 87.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 109.0, 95.0, 107.0, 90.0, 149.0, 152.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 153.0, 79.0, 146.0, 145.0, 90.0, 120.0, 91.0, 151.0, 146.0], "value": 126.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 56.0, 56.0, 57.0, 59.0, 57.0, 60.0, 57.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 71.0, 84.0, 105.0, 107.0, 99.0, 78.0, 69.0, 70.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 88.0, 93.0, 91.0, 86.0, 87.0, 88.0, 89.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 147.0, 149.0, 163.0, 162.0, 147.0, 206.0, 183.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [235.0, 239.0, 233.0, 175.0, 180.0, 171.0, 179.0, 224.0, 239.0, 196.0], "value": 207.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 81.0, 71.0, 71.0, 68.0, 71.0, 67.0, 83.0, 88.0], "value": 73.9, "unit": "score"}]}]} 10:40:31 INFO - Return code: 0 10:40:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:40:31 ERROR - # TBPL SUCCESS # 10:40:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:40:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:40:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:40:31 INFO - 2016-10-04 10:40:31.922 screenresolution[1671:10654] starting screenresolution argv=screenresolution get 10:40:31 INFO - 2016-10-04 10:40:31.924 screenresolution[1671:10654] Display 0: 1600x1200x32@60 10:40:31 INFO - 2016-10-04 10:40:31.930 screenresolution[1672:10657] starting screenresolution argv=screenresolution list 10:40:31 INFO - Available Modes on Display 0 10:40:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:40:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:40:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:40:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:40:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:40:32 INFO - Intel Iris: 10:40:32 INFO - Chipset Model: Intel Iris 10:40:32 INFO - Type: GPU 10:40:32 INFO - Bus: Built-In 10:40:32 INFO - VRAM (Dynamic, Max): 1536 MB 10:40:32 INFO - Vendor: Intel (0x8086) 10:40:32 INFO - Device ID: 0x0a2e 10:40:32 INFO - Revision ID: 0x0009 10:40:32 INFO - Displays: 10:40:32 INFO - Display: 10:40:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:40:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:40:32 INFO - Display Serial Number: 1600x1200 60 10:40:32 INFO - Main Display: Yes 10:40:32 INFO - Mirror: Off 10:40:32 INFO - Online: Yes 10:40:32 INFO - Rotation: Supported 10:40:32 INFO - Return code: 0 10:40:32 INFO - Running post-action listener: _resource_record_post_action 10:40:32 INFO - [mozharness: 2016-10-04 17:40:32.172711Z] Finished run-tests step (success) 10:40:32 INFO - Running post-run listener: _resource_record_post_run 10:40:32 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37493248; Write bytes: 711330304; Read time: 378; Write time: 2112 10:40:32 INFO - TinderboxPrint: CPU usage
54.3% 10:40:32 INFO - TinderboxPrint: I/O read bytes / time
37,493,248 / 378 10:40:32 INFO - TinderboxPrint: I/O write bytes / time
711,330,304 / 2,112 10:40:32 INFO - TinderboxPrint: CPU idle
2,216.0 (45.7%) 10:40:32 INFO - TinderboxPrint: CPU system
979.3 (20.2%) 10:40:32 INFO - TinderboxPrint: CPU user
1,654.3 (34.1%) 10:40:32 INFO - TinderboxPrint: Swap in / out
445,087,744 / 0 10:40:32 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395624960; Write bytes: 389551616; Read time: 29520; Write time: 782 10:40:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35666432; Write bytes: 300758016; Read time: 358; Write time: 1284 10:40:32 INFO - Running post-run listener: _upload_blobber_files 10:40:32 INFO - Blob upload gear active. 10:40:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:40:32 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:40:32 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:40:33 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:40:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:40:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:34 INFO - (blobuploader) - INFO - Done attempting. 10:40:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:40:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:40:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:35 INFO - (blobuploader) - INFO - Done attempting. 10:40:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:40:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:40:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:37 INFO - (blobuploader) - INFO - Done attempting. 10:40:37 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:37 INFO - Return code: 0 10:40:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51272a819f0610e56f86b172405f582adab9fb31a4a7a4aaee3366427fa20514d4290795ef6ab4f90a6aa04a1c0f7473b674896833884938e3f704ad6fb10bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e78562a94045c30e0e4121b2cc3fcf0febbcf05c26e74a6dfa7d64d6a922f2f6d722533c999cc4ee4ef1d8af69e7db2c1f8aeba91cb5ae288e7bef9c03a505b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60852dc6f98d59cc9d827025b8a171312f82c8bd6ea353a765a34fc3638d31a15e11ef718f42f25e7b50cdb2a8e7150a019931d9e242aac565a2e737f27568bf"} 10:40:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:37 INFO - Contents: 10:40:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51272a819f0610e56f86b172405f582adab9fb31a4a7a4aaee3366427fa20514d4290795ef6ab4f90a6aa04a1c0f7473b674896833884938e3f704ad6fb10bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e78562a94045c30e0e4121b2cc3fcf0febbcf05c26e74a6dfa7d64d6a922f2f6d722533c999cc4ee4ef1d8af69e7db2c1f8aeba91cb5ae288e7bef9c03a505b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60852dc6f98d59cc9d827025b8a171312f82c8bd6ea353a765a34fc3638d31a15e11ef718f42f25e7b50cdb2a8e7150a019931d9e242aac565a2e737f27568bf"} 10:40:37 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:37 INFO - Copying logs to upload dir... 10:40:37 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.166394 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 10:40:37.411781) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 10:40:37.415814) ========= 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.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/Autoland/sha512/51272a819f0610e56f86b172405f582adab9fb31a4a7a4aaee3366427fa20514d4290795ef6ab4f90a6aa04a1c0f7473b674896833884938e3f704ad6fb10bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e78562a94045c30e0e4121b2cc3fcf0febbcf05c26e74a6dfa7d64d6a922f2f6d722533c999cc4ee4ef1d8af69e7db2c1f8aeba91cb5ae288e7bef9c03a505b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60852dc6f98d59cc9d827025b8a171312f82c8bd6ea353a765a34fc3638d31a15e11ef718f42f25e7b50cdb2a8e7150a019931d9e242aac565a2e737f27568bf"} build_url:https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/fSZ6RZJjTuyr7vohtLCzFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51272a819f0610e56f86b172405f582adab9fb31a4a7a4aaee3366427fa20514d4290795ef6ab4f90a6aa04a1c0f7473b674896833884938e3f704ad6fb10bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e78562a94045c30e0e4121b2cc3fcf0febbcf05c26e74a6dfa7d64d6a922f2f6d722533c999cc4ee4ef1d8af69e7db2c1f8aeba91cb5ae288e7bef9c03a505b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60852dc6f98d59cc9d827025b8a171312f82c8bd6ea353a765a34fc3638d31a15e11ef718f42f25e7b50cdb2a8e7150a019931d9e242aac565a2e737f27568bf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 10:40:37.456543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 10:40:37.457239) ========= 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.pVs8iszYKI/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.sX7gFOyXOV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005130 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 10:40:37.508071) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 10:40:37.508686) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 10:40:37.509327) ========= ========= Total master_lag: 3.52 =========