builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1478214849.79 results: success (0) buildid: 20161103030205 builduid: 1d1483cf12d64dbbac00c1ce9f64e2ad revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:09.791571) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:09.792033) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:09.792323) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:09.881304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:09.881671) ========= 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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-03 16:14:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-11-03 16:14:10 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.573333 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:10.532630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:10.532981) ========= 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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.027269 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:10.597880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 16:14:10.598177) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ade8d4a63e57560410de106450f37b50ed71cca5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ade8d4a63e57560410de106450f37b50ed71cca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-03 16:14:10,682 truncating revision to first 12 chars 2016-11-03 16:14:10,683 Setting DEBUG logging. 2016-11-03 16:14:10,683 attempt 1/10 2016-11-03 16:14:10,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ade8d4a63e57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 16:14:11,946 unpacking tar archive at: mozilla-central-ade8d4a63e57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.532378 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 16:14:12.225788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:12.226135) ========= script_repo_revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:12.226539) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:12.226852) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 16:14:12.258331) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-03 16:14:12.258677) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:12 INFO - MultiFileLogger online at 20161103 16:14:12 in /builds/slave/test 16:14:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:14:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:14:12 INFO - {'append_to_log': False, 16:14:12 INFO - 'base_work_dir': '/builds/slave/test', 16:14:12 INFO - 'blob_upload_branch': 'Firefox', 16:14:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:14:12 INFO - 'branch': 'Firefox', 16:14:12 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:12 INFO - 'config_files': ('talos/mac_config.py',), 16:14:12 INFO - 'default_actions': ('clobber', 16:14:12 INFO - 'read-buildbot-config', 16:14:12 INFO - 'download-and-extract', 16:14:12 INFO - 'populate-webroot', 16:14:12 INFO - 'create-virtualenv', 16:14:12 INFO - 'install', 16:14:12 INFO - 'run-tests'), 16:14:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:12 INFO - 'download_minidump_stackwalk': True, 16:14:12 INFO - 'download_symbols': 'ondemand', 16:14:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:14:12 INFO - 'tooltool.py': '/tools/tooltool.py', 16:14:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:14:12 INFO - '/tools/misc-python/virtualenv.py')}, 16:14:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:12 INFO - 'installer_path': 'installer.exe', 16:14:12 INFO - 'log_level': 'info', 16:14:12 INFO - 'log_name': 'talos', 16:14:12 INFO - 'log_to_console': True, 16:14:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:14:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:14:12 INFO - 'opt_config_files': (), 16:14:12 INFO - 'pip_index': False, 16:14:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:14:12 INFO - 'cmd': ('bash', 16:14:12 INFO - '-c', 16:14:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:14:12 INFO - 'enabled': True, 16:14:12 INFO - 'halt_on_failure': False, 16:14:12 INFO - 'name': 'check_screen_resolution'},), 16:14:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:14:12 INFO - 'cmd': ('bash', 16:14:12 INFO - '-c', 16:14:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:14:12 INFO - 'enabled': True, 16:14:12 INFO - 'halt_on_failure': False, 16:14:12 INFO - 'name': 'check_screen_resolution'},), 16:14:12 INFO - 'run_cmd_checks_enabled': True, 16:14:12 INFO - 'sps_profile': False, 16:14:12 INFO - 'sps_profile_interval': 0, 16:14:12 INFO - 'suite': 'dromaeojs', 16:14:12 INFO - 'system_bits': '32', 16:14:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:12 INFO - 'title': 't-yosemite-r7-0110', 16:14:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:12 INFO - 'use_talos_json': True, 16:14:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:14:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:12 INFO - 'work_dir': 'build'} 16:14:12 INFO - [mozharness: 2016-11-03 23:14:12.471605Z] Running clobber step. 16:14:12 INFO - Running pre-action listener: _resource_record_pre_action 16:14:12 INFO - Running main action method: clobber 16:14:12 INFO - rmtree: /builds/slave/test/build 16:14:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:14:14 INFO - Running post-action listener: _resource_record_post_action 16:14:14 INFO - [mozharness: 2016-11-03 23:14:14.693780Z] Finished clobber step (success) 16:14:14 INFO - [mozharness: 2016-11-03 23:14:14.693914Z] Running read-buildbot-config step. 16:14:14 INFO - Running pre-action listener: _resource_record_pre_action 16:14:14 INFO - Running main action method: read_buildbot_config 16:14:14 INFO - Using buildbot properties: 16:14:14 INFO - { 16:14:14 INFO - "project": "", 16:14:14 INFO - "product": "firefox", 16:14:14 INFO - "script_repo_revision": "production", 16:14:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:14:14 INFO - "repository": "", 16:14:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:14:14 INFO - "buildid": "20161103030205", 16:14:14 INFO - "stage_platform": "macosx64", 16:14:14 INFO - "basedir": "/builds/slave/test", 16:14:14 INFO - "nightly_build": "True", 16:14:14 INFO - "buildnumber": 2, 16:14:14 INFO - "slavename": "t-yosemite-r7-0110", 16:14:14 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:14:14 INFO - "platform": "yosemite_r7", 16:14:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:14:14 INFO - "branch": "mozilla-central", 16:14:14 INFO - "repo_path": "mozilla-central", 16:14:14 INFO - "revision": "ade8d4a63e57560410de106450f37b50ed71cca5", 16:14:14 INFO - "pgo_build": "False", 16:14:14 INFO - "builduid": "1d1483cf12d64dbbac00c1ce9f64e2ad", 16:14:14 INFO - "slavebuilddir": "test" 16:14:14 INFO - } 16:14:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:14:14 INFO - Running post-action listener: _resource_record_post_action 16:14:14 INFO - [mozharness: 2016-11-03 23:14:14.697350Z] Finished read-buildbot-config step (success) 16:14:14 INFO - [mozharness: 2016-11-03 23:14:14.697460Z] Running download-and-extract step. 16:14:14 INFO - Running pre-action listener: _resource_record_pre_action 16:14:14 INFO - Running main action method: download_and_extract 16:14:14 INFO - mkdir: /builds/slave/test/build/tests 16:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:14 INFO - trying https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:14:14 INFO - Downloading https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/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 16:14:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/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 16:14:16 INFO - Downloaded 1199 bytes. 16:14:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:14:16 INFO - Using the following test package requirements: 16:14:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:14:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:14:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:14:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'jsshell-mac.zip'], 16:14:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:14:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:14:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:14:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:14:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:14:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:14:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:14:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:14:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:14:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:14:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:14:16 INFO - Http code: 200 16:14:16 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 16:14:16 INFO - x-amz-cf-id: 82LZSL_Tn8wD6TYNwWYH0kbeR4jPQZChHr0MD_J0PMGn_C0edVSR_w== 16:14:16 INFO - x-amz-version-id: _rhDVtjYVR2AWHp6omue.8KNYhlxpAk6 16:14:17 INFO - Expected file size: 16389341 16:14:17 INFO - Obtained file size: 16389341 16:14:23 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 16:14:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:14:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:14:23 INFO - Fetch https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:14:24 INFO - Http code: 200 16:14:24 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 16:14:24 INFO - x-amz-cf-id: zPVpc_pb4RVKa8_eNe2-WmKP6S2CVPPme5PK5UJ-LT0VUxqUrbaGmw== 16:14:24 INFO - x-amz-version-id: _rhDVtjYVR2AWHp6omue.8KNYhlxpAk6 16:14:25 INFO - Expected file size: 16389341 16:14:25 INFO - Obtained file size: 16389341 16:14:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:14:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:14:32 INFO - Fetch https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:14:32 INFO - Http code: 200 16:14:32 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 16:14:32 INFO - x-amz-cf-id: C3hHssxyoFF0gCJh74A8Grf3JKc7ceGc8Kq0TA6ZTLsarRiG6SiWEw== 16:14:32 INFO - x-amz-version-id: 7RYeycUgd9HGfpg45B.K3oJfMTsNa60B 16:14:33 INFO - Expected file size: 14409038 16:14:33 INFO - Obtained file size: 14409038 16:14:33 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')]} 16:14:33 INFO - trying https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:14:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:14:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/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 16:14:36 INFO - Downloaded 112090170 bytes. 16:14:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:14:36 INFO - mkdir: /builds/slave/test/properties 16:14:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:14:36 INFO - Writing to file /builds/slave/test/properties/build_url 16:14:36 INFO - Contents: 16:14:36 INFO - build_url:https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:14:37 INFO - Running post-action listener: _resource_record_post_action 16:14:37 INFO - Running post-action listener: set_extra_try_arguments 16:14:37 INFO - [mozharness: 2016-11-03 23:14:37.454751Z] Finished download-and-extract step (success) 16:14:37 INFO - [mozharness: 2016-11-03 23:14:37.454885Z] Running populate-webroot step. 16:14:37 INFO - Running pre-action listener: _resource_record_pre_action 16:14:37 INFO - Running main action method: populate_webroot 16:14:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:14:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:14:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:14:37 INFO - '--tppagecycles', 16:14:37 INFO - '7'], 16:14:37 INFO - 'tests': ['tsvgm']}}, 16:14:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:14:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:14:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:14:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:14:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['damp', 'tps']}, 16:14:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'tests': ['damp', 'tps']}, 16:14:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['dromaeo_dom']}, 16:14:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:14:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['basic_compositor_video']}, 16:14:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:14:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'other-e10s': {'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['a11yr', 16:14:37 INFO - 'ts_paint', 16:14:37 INFO - 'tpaint', 16:14:37 INFO - 'sessionrestore', 16:14:37 INFO - 'sessionrestore_no_auto_restore', 16:14:37 INFO - 'tabpaint']}, 16:14:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['tsvgx', 16:14:37 INFO - 'tsvgr_opacity', 16:14:37 INFO - 'tart', 16:14:37 INFO - 'tscrollx', 16:14:37 INFO - 'cart']}, 16:14:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:14:37 INFO - 'tsvgr_opacity', 16:14:37 INFO - 'tart', 16:14:37 INFO - 'tscrollx', 16:14:37 INFO - 'cart']}, 16:14:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'talos_options': ['--disable-e10s'], 16:14:37 INFO - 'tests': ['tp5o']}, 16:14:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'tests': ['tp5o']}, 16:14:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'talos_options': ['--disable-e10s', 16:14:37 INFO - '--xperf_path', 16:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:37 INFO - 'tests': ['tp5n']}, 16:14:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:37 INFO - 'talos_options': ['--xperf_path', 16:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:37 INFO - 'tests': ['tp5n']}}, 16:14:37 INFO - 'talos.zip': {'path': '', 16:14:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:14:37 INFO - Running post-action listener: _resource_record_post_action 16:14:37 INFO - [mozharness: 2016-11-03 23:14:37.473487Z] Finished populate-webroot step (success) 16:14:37 INFO - [mozharness: 2016-11-03 23:14:37.473598Z] Running create-virtualenv step. 16:14:37 INFO - Running pre-action listener: _resource_record_pre_action 16:14:37 INFO - Running main action method: create_virtualenv 16:14:37 INFO - Creating virtualenv /builds/slave/test/build/venv 16:14:37 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 16:14:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:14:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:14:37 INFO - Using real prefix '/tools/python27' 16:14:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:14:38 INFO - Installing distribute.............................................................................................................................................................................................done. 16:14:41 INFO - Installing pip.................done. 16:14:41 INFO - Return code: 0 16:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:14:41 INFO - Reading from file tmpfile_stdout 16:14:41 INFO - Output received: 16:14:41 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) 16:14:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:14:41 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')]} 16:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 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')]} 16:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:41 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 16:14:41 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 16:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:41 INFO - 'HOME': '/Users/cltbld', 16:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:41 INFO - 'LOGNAME': 'cltbld', 16:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:41 INFO - 'MOZ_NO_REMOTE': '1', 16:14:41 INFO - 'NO_EM_RESTART': '1', 16:14:41 INFO - 'PAGER': '/bin/cat', 16:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:41 INFO - 'PWD': '/builds/slave/test', 16:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:41 INFO - 'SHELL': '/bin/bash', 16:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:41 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:41 INFO - 'USER': 'cltbld', 16:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:41 INFO - 'XPC_FLAGS': '0x0', 16:14:41 INFO - 'XPC_SERVICE_NAME': '0', 16:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:41 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 16:14:41 INFO - Cleaning up... 16:14:41 INFO - Return code: 0 16:14:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:14:41 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')]} 16:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 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')]} 16:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:41 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 16:14:41 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 16:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:41 INFO - 'HOME': '/Users/cltbld', 16:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:41 INFO - 'LOGNAME': 'cltbld', 16:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:41 INFO - 'MOZ_NO_REMOTE': '1', 16:14:41 INFO - 'NO_EM_RESTART': '1', 16:14:41 INFO - 'PAGER': '/bin/cat', 16:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:41 INFO - 'PWD': '/builds/slave/test', 16:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:41 INFO - 'SHELL': '/bin/bash', 16:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:41 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:41 INFO - 'USER': 'cltbld', 16:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:41 INFO - 'XPC_FLAGS': '0x0', 16:14:41 INFO - 'XPC_SERVICE_NAME': '0', 16:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:42 INFO - Downloading/unpacking psutil>=3.1.1 16:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:14:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:46 INFO - Installing collected packages: psutil 16:14:46 INFO - Running setup.py install for psutil 16:14:46 INFO - building 'psutil._psutil_osx' extension 16:14:46 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 16:14:46 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 16:14:46 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 16:14:46 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 16:14:47 INFO - building 'psutil._psutil_posix' extension 16:14:47 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 16:14:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:14:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:14:47 INFO - ^ 16:14:47 INFO - 1 warning generated. 16:14:47 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 16:14:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:47 INFO - Successfully installed psutil 16:14:47 INFO - Cleaning up... 16:14:47 INFO - Return code: 0 16:14:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:47 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:47 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 16:14:47 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 16:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:47 INFO - 'HOME': '/Users/cltbld', 16:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:47 INFO - 'LOGNAME': 'cltbld', 16:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:47 INFO - 'MOZ_NO_REMOTE': '1', 16:14:47 INFO - 'NO_EM_RESTART': '1', 16:14:47 INFO - 'PAGER': '/bin/cat', 16:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:47 INFO - 'PWD': '/builds/slave/test', 16:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:47 INFO - 'SHELL': '/bin/bash', 16:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:47 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:47 INFO - 'USER': 'cltbld', 16:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:47 INFO - 'XPC_FLAGS': '0x0', 16:14:47 INFO - 'XPC_SERVICE_NAME': '0', 16:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:14:50 INFO - Installing collected packages: mozsystemmonitor 16:14:50 INFO - Running setup.py install for mozsystemmonitor 16:14:50 INFO - Successfully installed mozsystemmonitor 16:14:50 INFO - Cleaning up... 16:14:50 INFO - Return code: 0 16:14:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:14:50 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')]} 16:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:50 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')]} 16:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:50 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:50 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 16:14:50 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 16:14:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:50 INFO - 'HOME': '/Users/cltbld', 16:14:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:50 INFO - 'LOGNAME': 'cltbld', 16:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:50 INFO - 'MOZ_NO_REMOTE': '1', 16:14:50 INFO - 'NO_EM_RESTART': '1', 16:14:50 INFO - 'PAGER': '/bin/cat', 16:14:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:50 INFO - 'PWD': '/builds/slave/test', 16:14:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:50 INFO - 'SHELL': '/bin/bash', 16:14:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:50 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:50 INFO - 'USER': 'cltbld', 16:14:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:50 INFO - 'XPC_FLAGS': '0x0', 16:14:50 INFO - 'XPC_SERVICE_NAME': '0', 16:14:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:50 INFO - Downloading/unpacking jsonschema==2.5.1 16:14:50 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 16:14:50 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 16:14:50 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 16:14:50 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 16:14:50 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 16:14:50 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 16:14:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:14:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:53 INFO - Downloading functools32-3.2.3-2.tar.gz 16:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:14:53 INFO - warning: no files found matching '*.txt' 16:14:53 INFO - no previously-included directories found matching 'build' 16:14:53 INFO - no previously-included directories found matching 'dist' 16:14:53 INFO - no previously-included directories found matching '.git*' 16:14:53 INFO - Installing collected packages: jsonschema, functools32 16:14:53 INFO - Running setup.py install for functools32 16:14:53 INFO - warning: no files found matching '*.txt' 16:14:53 INFO - no previously-included directories found matching 'build' 16:14:53 INFO - no previously-included directories found matching 'dist' 16:14:53 INFO - no previously-included directories found matching '.git*' 16:14:53 INFO - Successfully installed jsonschema functools32 16:14:53 INFO - Cleaning up... 16:14:53 INFO - Return code: 0 16:14:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:53 INFO - 'HOME': '/Users/cltbld', 16:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:53 INFO - 'LOGNAME': 'cltbld', 16:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:53 INFO - 'MOZ_NO_REMOTE': '1', 16:14:53 INFO - 'NO_EM_RESTART': '1', 16:14:53 INFO - 'PAGER': '/bin/cat', 16:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:53 INFO - 'PWD': '/builds/slave/test', 16:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:53 INFO - 'SHELL': '/bin/bash', 16:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:53 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:53 INFO - 'USER': 'cltbld', 16:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:53 INFO - 'XPC_FLAGS': '0x0', 16:14:53 INFO - 'XPC_SERVICE_NAME': '0', 16:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:14:54 INFO - Cleaning up... 16:14:54 INFO - Return code: 0 16:14:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:14: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')]} 16:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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')]} 16:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:54 INFO - 'HOME': '/Users/cltbld', 16:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:54 INFO - 'LOGNAME': 'cltbld', 16:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:54 INFO - 'MOZ_NO_REMOTE': '1', 16:14:54 INFO - 'NO_EM_RESTART': '1', 16:14:54 INFO - 'PAGER': '/bin/cat', 16:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:54 INFO - 'PWD': '/builds/slave/test', 16:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:54 INFO - 'SHELL': '/bin/bash', 16:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:54 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:54 INFO - 'USER': 'cltbld', 16:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:54 INFO - 'XPC_FLAGS': '0x0', 16:14:54 INFO - 'XPC_SERVICE_NAME': '0', 16:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:54 INFO - Downloading/unpacking blobuploader==1.2.4 16:14:54 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 16:14:54 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 16:14:54 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 16:14:54 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 16:14:54 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 16:14:54 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 16:14:56 INFO - Downloading blobuploader-1.2.4.tar.gz 16:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:14:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:14:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14: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 16:14:58 INFO - Downloading docopt-0.6.1.tar.gz 16:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:14:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:14:58 INFO - Running setup.py install for blobuploader 16:14:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:14:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:14:58 INFO - Running setup.py install for requests 16:14:58 INFO - Running setup.py install for docopt 16:14:59 INFO - Successfully installed blobuploader requests docopt 16:14:59 INFO - Cleaning up... 16:14:59 INFO - Return code: 0 16:14:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14: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')]} 16:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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')]} 16:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:59 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:14:59 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 16:14:59 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 16:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:59 INFO - 'HOME': '/Users/cltbld', 16:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:59 INFO - 'LOGNAME': 'cltbld', 16:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:59 INFO - 'MOZ_NO_REMOTE': '1', 16:14:59 INFO - 'NO_EM_RESTART': '1', 16:14:59 INFO - 'PAGER': '/bin/cat', 16:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:59 INFO - 'PWD': '/builds/slave/test', 16:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:59 INFO - 'SHELL': '/bin/bash', 16:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:14:59 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:59 INFO - 'USER': 'cltbld', 16:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:59 INFO - 'XPC_FLAGS': '0x0', 16:14:59 INFO - 'XPC_SERVICE_NAME': '0', 16:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YY0CS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dqe134-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-c0QSuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rafG56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-57vKYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5b7QH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Mj4ze7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0U7jpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-EHy2vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-tExKb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-wSx1kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rxKcNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-hRDjPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qAroFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-I3XW3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:00 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BPs4sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:01 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-7rypD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:01 INFO - Running setup.py install for manifestparser 16:15:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:01 INFO - Running setup.py install for mozcrash 16:15:01 INFO - Running setup.py install for mozdebug 16:15:01 INFO - Running setup.py install for mozdevice 16:15:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:15:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:01 INFO - Running setup.py install for mozfile 16:15:01 INFO - Running setup.py install for mozhttpd 16:15:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Running setup.py install for mozinfo 16:15:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Running setup.py install for mozInstall 16:15:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Running setup.py install for mozleak 16:15:02 INFO - Running setup.py install for mozlog 16:15:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Running setup.py install for moznetwork 16:15:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:02 INFO - Running setup.py install for mozprocess 16:15:03 INFO - Running setup.py install for mozprofile 16:15:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:03 INFO - Running setup.py install for mozrunner 16:15:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:03 INFO - Running setup.py install for mozscreenshot 16:15:03 INFO - Running setup.py install for moztest 16:15:03 INFO - Running setup.py install for mozversion 16:15:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:03 INFO - Cleaning up... 16:15:03 INFO - Return code: 0 16:15:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:03 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:15:03 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 16:15:03 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 16:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:03 INFO - 'HOME': '/Users/cltbld', 16:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:03 INFO - 'LOGNAME': 'cltbld', 16:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:03 INFO - 'MOZ_NO_REMOTE': '1', 16:15:03 INFO - 'NO_EM_RESTART': '1', 16:15:03 INFO - 'PAGER': '/bin/cat', 16:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:03 INFO - 'PWD': '/builds/slave/test', 16:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:03 INFO - 'SHELL': '/bin/bash', 16:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:15:03 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:03 INFO - 'USER': 'cltbld', 16:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:03 INFO - 'XPC_FLAGS': '0x0', 16:15:03 INFO - 'XPC_SERVICE_NAME': '0', 16:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-HMf97h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5D3_nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KvMosX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nW5rjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CdqnFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6uweJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-OT3y0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-sezqgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:04 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)) 16:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XHjfGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-M8ViRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-h1pcgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xXdBUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-E0KrbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-mG7Qqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-uDOgZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LfM7O4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:05 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)) 16:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-VMaKkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:05 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)) 16:15:05 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)) 16:15:05 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)) 16:15:05 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)) 16:15:05 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)) 16:15:05 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)) 16:15:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:08 INFO - Downloading blessings-1.6.tar.gz 16:15:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:08 INFO - Installing collected packages: blessings 16:15:08 INFO - Running setup.py install for blessings 16:15:08 INFO - Successfully installed blessings 16:15:08 INFO - Cleaning up... 16:15:08 INFO - Return code: 0 16:15:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:09 INFO - Reading from file tmpfile_stdout 16:15:09 INFO - Current package versions: 16:15:09 INFO - blessings == 1.6 16:15:09 INFO - blobuploader == 1.2.4 16:15:09 INFO - docopt == 0.6.1 16:15:09 INFO - functools32 == 3.2.3-2 16:15:09 INFO - jsonschema == 2.5.1 16:15:09 INFO - manifestparser == 1.1 16:15:09 INFO - mozInstall == 1.12 16:15:09 INFO - mozcrash == 0.17 16:15:09 INFO - mozdebug == 0.1 16:15:09 INFO - mozdevice == 0.48 16:15:09 INFO - mozfile == 1.2 16:15:09 INFO - mozhttpd == 0.7 16:15:09 INFO - mozinfo == 0.9 16:15:09 INFO - mozleak == 0.1 16:15:09 INFO - mozlog == 3.4 16:15:09 INFO - moznetwork == 0.27 16:15:09 INFO - mozprocess == 0.23 16:15:09 INFO - mozprofile == 0.28 16:15:09 INFO - mozrunner == 6.12 16:15:09 INFO - mozscreenshot == 0.1 16:15:09 INFO - mozsystemmonitor == 0.3 16:15:09 INFO - moztest == 0.7 16:15:09 INFO - mozversion == 1.4 16:15:09 INFO - psutil == 3.1.1 16:15:09 INFO - requests == 1.2.3 16:15:09 INFO - wsgiref == 0.1.2 16:15:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:09 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')]} 16:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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')]} 16:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:15:09 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 16:15:09 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 16:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:09 INFO - 'HOME': '/Users/cltbld', 16:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:09 INFO - 'LOGNAME': 'cltbld', 16:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:09 INFO - 'MOZ_NO_REMOTE': '1', 16:15:09 INFO - 'NO_EM_RESTART': '1', 16:15:09 INFO - 'PAGER': '/bin/cat', 16:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:09 INFO - 'PWD': '/builds/slave/test', 16:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:09 INFO - 'SHELL': '/bin/bash', 16:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:15:09 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:09 INFO - 'USER': 'cltbld', 16:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:09 INFO - 'XPC_FLAGS': '0x0', 16:15:09 INFO - 'XPC_SERVICE_NAME': '0', 16:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 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)) 16:15:09 INFO - Cleaning up... 16:15:09 INFO - Return code: 0 16:15:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:09 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')]} 16:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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')]} 16:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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 0x10ecef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead95e0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, '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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 16:15:09 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 16:15:09 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 16:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:09 INFO - 'HOME': '/Users/cltbld', 16:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:09 INFO - 'LOGNAME': 'cltbld', 16:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:09 INFO - 'MOZ_NO_REMOTE': '1', 16:15:09 INFO - 'NO_EM_RESTART': '1', 16:15:09 INFO - 'PAGER': '/bin/cat', 16:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:09 INFO - 'PWD': '/builds/slave/test', 16:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:09 INFO - 'SHELL': '/bin/bash', 16:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:15:09 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:09 INFO - 'USER': 'cltbld', 16:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:09 INFO - 'XPC_FLAGS': '0x0', 16:15:09 INFO - 'XPC_SERVICE_NAME': '0', 16:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:09 INFO - Cleaning up... 16:15:09 INFO - Return code: 0 16:15:09 INFO - Running post-action listener: _resource_record_post_action 16:15:09 INFO - Running post-action listener: _start_resource_monitoring 16:15:09 INFO - Starting resource monitoring. 16:15:09 INFO - [mozharness: 2016-11-03 23:15:09.688618Z] Finished create-virtualenv step (success) 16:15:09 INFO - [mozharness: 2016-11-03 23:15:09.689528Z] Running install step. 16:15:09 INFO - Running pre-action listener: _resource_record_pre_action 16:15:09 INFO - Running main action method: install 16:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:09 INFO - Reading from file tmpfile_stdout 16:15:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:10 INFO - Reading from file tmpfile_stdout 16:15:10 INFO - Output received: 16:15:10 INFO - Usage: mozinstall [options] installer 16:15:10 INFO - Options: 16:15:10 INFO - -h, --help show this help message and exit 16:15:10 INFO - -d DEST, --destination=DEST 16:15:10 INFO - Directory to install application into. [default: 16:15:10 INFO - "/builds/slave/test"] 16:15:10 INFO - --app=APP Application being installed. [default: firefox] 16:15:10 INFO - mkdir: /builds/slave/test/build/application 16:15:10 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'] 16:15:10 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 16:15:45 INFO - Reading from file tmpfile_stdout 16:15:45 INFO - Output received: 16:15:45 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 16:15:45 INFO - Running post-action listener: _resource_record_post_action 16:15:45 INFO - [mozharness: 2016-11-03 23:15:45.123414Z] Finished install step (success) 16:15:45 INFO - [mozharness: 2016-11-03 23:15:45.123549Z] Running run-tests step. 16:15:45 INFO - Running pre-action listener: _resource_record_pre_action 16:15:45 INFO - Running main action method: run_tests 16:15:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:15:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:15:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:15:45 INFO - 2016-11-03 16:15:45.136 screenresolution[1656:8527] starting screenresolution argv=screenresolution get 16:15:45 INFO - 2016-11-03 16:15:45.139 screenresolution[1656:8527] Display 0: 1600x1200x32@60 16:15:45 INFO - 2016-11-03 16:15:45.144 screenresolution[1657:8529] starting screenresolution argv=screenresolution list 16:15:45 INFO - Available Modes on Display 0 16:15:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:15:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:15:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:15:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:15:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:15:45 INFO - Intel Iris: 16:15:45 INFO - Chipset Model: Intel Iris 16:15:45 INFO - Type: GPU 16:15:45 INFO - Bus: Built-In 16:15:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:15:45 INFO - Vendor: Intel (0x8086) 16:15:45 INFO - Device ID: 0x0a2e 16:15:45 INFO - Revision ID: 0x0009 16:15:45 INFO - Displays: 16:15:45 INFO - Display: 16:15:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:15:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:15:45 INFO - Display Serial Number: 1600x1200 60 16:15:45 INFO - Main Display: Yes 16:15:45 INFO - Mirror: Off 16:15:45 INFO - Online: Yes 16:15:45 INFO - Rotation: Supported 16:15:45 INFO - Return code: 0 16:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:15:45 INFO - Python 2.7.3 16:15:45 INFO - Return code: 0 16:15:45 INFO - grabbing minidump binary from tooltool 16:15:45 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')]} 16:15:45 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 0x10eb03d30>, '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 0x10e929db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eafbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:15:45 INFO - Return code: 0 16:15:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:15:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:15:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:15:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:15:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:15:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bSXNkrdSsr/Render', 16:15:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:45 INFO - 'HOME': '/Users/cltbld', 16:15:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:45 INFO - 'LOGNAME': 'cltbld', 16:15:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:45 INFO - 'MOZ_NO_REMOTE': '1', 16:15:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:45 INFO - 'NO_EM_RESTART': '1', 16:15:45 INFO - 'PAGER': '/bin/cat', 16:15:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:45 INFO - 'PWD': '/builds/slave/test', 16:15:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:45 INFO - 'SHELL': '/bin/bash', 16:15:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZPJCl2iFO/Listeners', 16:15:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 16:15:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:45 INFO - 'USER': 'cltbld', 16:15:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:45 INFO - 'XPC_FLAGS': '0x0', 16:15:45 INFO - 'XPC_SERVICE_NAME': '0', 16:15:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:15:45 INFO - mozversion application_buildid: 20161103030205 16:15:45 INFO - mozversion application_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 16:15:45 INFO - mozversion application_display_name: Nightly 16:15:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:15:45 INFO - mozversion application_name: Firefox 16:15:45 INFO - mozversion application_remotingname: firefox 16:15:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:15:45 INFO - mozversion application_vendor: Mozilla 16:15:45 INFO - mozversion application_version: 52.0a1 16:15:45 INFO - mozversion platform_buildid: 20161103030205 16:15:45 INFO - mozversion platform_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 16:15:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:15:45 INFO - mozversion platform_version: 52.0a1 16:15:45 INFO - using testdate: 1478214945 16:15:45 INFO - actual date: 1478214945 16:15:45 INFO - starting webserver on 'localhost:49279' 16:15:45 INFO - SUITE-START | Running 2 tests 16:15:45 INFO - TEST-START | dromaeo_css 16:15:45 INFO - operating with platform_type : mac_ 16:15:45 INFO - Initialising browser for dromaeo_css test... 16:15:45 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpcTZKrK/profile http://localhost:49279/getInfo.html) 16:15:48 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 16:15:48 INFO - PROCESS | 1667 | colorDepth:24 16:15:48 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 16:15:48 INFO - PROCESS | 1667 | __metrics 16:15:48 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:15:48 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:15:49 INFO - TEST-INFO | 1667: exit 0 16:15:49 INFO - Browser initialized. 16:15:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:15:49 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpcTZKrK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:01 INFO - PROCESS | 1671 | RSS: Main: 239366144 16:16:01 INFO - PROCESS | 1671 | 16:16:52 INFO - PROCESS | 1671 | 1478215012845 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] 16:19:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 16:19:07 INFO - PROCESS | 1671 | RSS: Main: 377921536 16:19:07 INFO - PROCESS | 1671 | 16:22:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 16:22:11 INFO - PROCESS | 1671 | RSS: Main: 363147264 16:22:11 INFO - PROCESS | 1671 | 16:25:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 16:25:14 INFO - PROCESS | 1671 | RSS: Main: 333692928 16:25:14 INFO - PROCESS | 1671 | 16:28:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 16:28:17 INFO - PROCESS | 1671 | RSS: Main: 340430848 16:28:17 INFO - PROCESS | 1671 | 16:31:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 16:31:21 INFO - PROCESS | 1671 | RSS: Main: 332697600 16:31:21 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 16:34:24 INFO - PROCESS | 1671 | RSS: Main: 408944640 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | __start_tp_report 16:34:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:34:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:34:24 INFO - PROCESS | 1671 | |i|pagename|runs| 16:34:24 INFO - PROCESS | 1671 | |0;dojo.html;2759.558470644697;3268.3626692794915;3287.9013629591122;3308.768386212966;3352.5306457095994;5367.677939323643;5393.757280922358;5469.589780766439;5491.862703432416;5509;5433.646812957155;5479.4520547945185;5554.777808887639;5582.358028826686;5608.719564021792;5010.498950104979;5031.044101620699;5048.899022019557;5060;5108.616853735966;4285.849995250167;5082.491750824906;5167.534921857032;5171.224316352558;5180.818671346504;4978.452370239271;5136.280920671109;5149.201873709581;5160.096983027974;5163.380394352683;4999.325091112726;5049.5455409013;5071.366079240095;5078.390593128848;5101.591872650179;4528.343390208402;4792.736399498026;5043.697378157322;5051.520105589963;5143.9454911743005;5482.890342193174;5490.3411590608985;5503.587230957654;5505.394406615269;5530.889382212374;5753.942460575383;5826.737796799154;5830.387809280007;5904.675242861641;5937.584369094165;4897.608191344684;5993.940060599425;6030.427109424598;6112.847178820521;6119;5602.523785478198;5610.971945140248;5618.8033418830555;5622.887542249214;5655.717214139277;5020.523050310215;6137.7851775187655;6202.689865506707;6221.688915554204;6236.220472440945;5673.546116310686;5745.68398738065;5750.827475175752;5833.125031245348;5930.169776231331;5495.395506494282;6020.4280593343565;6065.150878876961;6078.209832721806;6103.6032657877095;5944.108383742473;5954.55340849438;5976.223090998143;5990.101484777231;5990.9101363479585;5827.708614569255;5866.765329386777;5968.134620480093;5984.371640977722;5987.161797348374;4026.7382620129597;4747.2879068140055;4761.857144285677;4767.213409787346;4774.236122220428;4209.915801683949;4280.6789490788315;4311.676624253194;4345.587169218919;4368.934465983013;5602.243697100921;5619.157126431068;5645.745941432563;5655.632383895034;5696.8006119785605;6061.878762424727;6095.542834287447;6122.693865306717;6128.264608247039;6130.417610327012;4879.463259041502;5383.946160538423;5459.890802183934;5599.496045355938;5600.747966341524;5190.688558686491;5193.948060519347;5196.58427325813;5198.16829307309;5206.895862082737;6149.569530133027;6213.974694175501;6214.347493513206;6218.844528886814;6222.782202622796;5997.430244126801;6009.278886533469;6010.669413182317;6016.6690832002905;6016.699165041818;6173.351798061139;6212.720427580679;6228.377162283736;6229.750809967732;6230.439260466398;5729.455701708331;5870.14882841994;5885.499732522783;5929.288485381781;5944.108383742473;5959.28488581373;5980.910286345621;5985.162077309267;5987.700614969322;6018.1875446815875;5724.4561766632105;5831.883362332815;5880.117982302518;5894.263217097863;5940.79207227745;5874.530037597067;5896.941030589639;5904.704764761659;5916.615419997686;5922.170896074639;5551.167324901297;5593.105103183471;5597.776088956559;5629.211910332475;5663.716814159358;5796.507296879812;5856.912146317723;5878.412158784087;5890.617109887844;5918.082697181943;5692.6299790513485;5709.286339207599;5713.8285851424525;5723.8569035774435;5750.367459579526 16:34:24 INFO - PROCESS | 1671 | |1;ext.html;453.5328611530124;499.8825276060125;519.4934938435028;558.8770470496491;566.2129639800676;36873.26253474929;38897.80551097244;38964;39028.414573781345;39067.82796516102;39746.20507589846;40370.79814600923;40408.191836163256;40437.98905027368;40489;9260.490673012982;9457.243420526342;9464.479453630047;9464.858027129598;9466.857997130048;9331.813363732721;9433.669821556246;9448;9455.385399949017;9468.479233642147;9429.528523573828;9474.242060635168;9478.194353479963;9489.952550237274;9498.762530936747;9397.248220142372;9450.385724927926;9464;9467.337286389955;9494;9292.117248861347;9396.436213827124;9403.62385504579;9405.57674904631;9414.482203478807;37704.05739856498;38135.61864381376;38202.23595528074;38225.617743822484;38309.61690383089;3266.673332666738;3342.715869151125;3434.6565343465704;3495.563054618173;3741.8690345837763;27932.301692457648;29649.110526684235;30276.54585181224;30297.848510757307;30327.848360758057;3229.337985712923;3241.918952026195;3246.626637936644;3319.850606722703;3355.312161006988;303952.92682561127;361562;365428.97566779895;368937;368957;57466;57687.71156144192;57722.71138644364;57796.711016445486;57906.71046644824;60316;60352.79294414087;60435;60442.39557604455;60651;15213.6196595086;15443.536693899201;15443.845561544465;15472.845271547141;15530.378784848479;11907.226030308002;11980.940095299467;11983.46074426653;11983.940080299717;12007.579734709418;13712.862871371359;13786.517471888435;15523.844761552466;16001;16289.185540722916;6671.199456065303;6847.349501797321;6848.596037812236;6849.897251541231;6882.242953275236;651.2185377546963;739.5340935210891;793.1434051224663;877.863931090682;1043.3062013760764;27314;28110.43779124406;28832.855835720686;28909.710902890703;29779;876.6186708781573;897.8428774964356;1044.9268551201494;1054.3094273251033;1070.1706177712335;14944.476943306932;15160.862935279898;15168.24158792056;15291.61770955735;15423.00675558131;614492;621906;622152;622743;623082;47923.81142828603;50081.74959125181;50371.74814125906;50481.670524363806;50528.242076368886;1231.8398608180878;1291.2446218962014;1319.0634649398842;1362.6389006913373;1365.5766712319212;27669.30826729348;29190.56214156832;29793.106206812965;29845.5523167157;30894.38211235808;47137;47616.761916191586;47667.523324766305;47738;47799.52200477951;504584;506275;607195;620374;623470.8826455838;24831;25021.12426065152;25086.49827003413;25120;25142.62286065747;44823.10353792971;45061.873453163935;45286.09427811491;46842.53157468518;48024.51975480201;37801.432978504796;37915.81042094772;38489.10614944025;38504.80747596244;39380;38100;38145.809270954574;38626.80686596549;38800.41799372955;39413.80293098516;36908.630913691595;37303.25393492169;37856.621433786415;39516.80241598774;40395;39205;39355.01612459712;39725.60274397219;40768.45783530056;41220.1755964873;28861.85569072141;29909;31438;32216;32277.193070173435 16:34:24 INFO - PROCESS | 1671 | |2;jquery.html;329.6818570079872;348.19393104961995;361.5264004154558;363.13392558747387;372.4580735030531;14798.964072514927;15544.844551554495;18471.445856624305;18650.906745466255;19327;17442.953422794642;17695.380661676845;19451.513712157204;20140;20346.28787992417;5203.375594928614;5746.942530574704;6055.606385584945;6125.540584456161;6164.291106522763;5695.5443564514735;6076.3316035236085;6120;6130;6152.107944348067;5975;6052.576319657625;6067.453929146376;6068.089786532011;6076.422739839708;5828.708564571797;6033.547483938678;6062.545309101813;6069.211002569682;6128.846778830521;5950.8809823803285;5956.583039187259;5965.761369545213;5973.283206015262;5988.221531200959;12930.741385172243;13075;13165.670858228525;13373.398197081058;13373.799393009112;2276.817854571647;2431.9270421887372;2462.938426539333;2701.986490067537;2883.4377296427206;9711.61153553857;9808.852867206997;9835.065668761456;10220.74448138795;10303.845442318372;1823.972640410395;1833.2025568877582;1861.8324350808493;1947.8831270123837;1971.4578490914969;117070.41464792707;125111.12333315007;125503.37248313791;126601;129611.35194324062;9923.950380248052;10949.507272172772;11275.54897804095;11392.544298228144;11490.827637585442;14730.968832181645;14744.705105898036;14762.852371476363;14762.966592338647;14782.560877455315;8032.469632110588;8344.707935222359;8347;8438.915610843813;8500.14998500145;8194.05768336637;8200.26197642215;8200.507969521848;8311.792205194797;8321.209485098905;4488.102379524108;4502.57225563571;4515.774211289422;4524.230880750215;4603.976980115145;1862.7950925398195;1919.5680971781237;1920.1359388275332;1920.2990908318363;1920.9807901920801;687.7558615556383;761.5621017914722;803.7789607857769;865.4158443050916;953.6376177052814;19388.612227755366;21115.683264751042;21135.78864211369;21229.363119106452;21544.892275538834;903.7333986473875;926.8470702334039;948.231932134959;958.0551480494468;964.4213471916794;9172.816543669223;10533.262671612914;10995.450227488594;11043.226974111734;11062.004419602274;589329;592290;596172;601948;612749;115047;119575.80424195647;129876.05185922219;137475.31262343825;141180;824.8309096635176;1011.6610935336597;1027.7276521721749;1028.5577201803146;1031.63377001165;14299.64250893736;18543.629127417105;19337.51656208606;19374.128164232927;19396.903015485397;45224.64326070223;49935.250971236186;52191.95616087581;53205;53884;72227;79335.60332198303;79758;82056.17943820724;82876.58561707153;7655.57894315818;7866.646000930086;8001.279884810395;8144.511329320259;8337.708180213664;12330.876691232972;12614;12664.746705065663;13003.674908127372;13082.934585327394;10793.298435601975;11144.721381965515;11297.096232301561;11409.771804564029;11444.94277528607;10774.676759697222;10838.295510792088;10999.890001099886;11258;11377.601783937522;10681;11181.776364472827;11722.296662200295;11753.353565553634;11843.28940263587;7117.683228602725;7972.92027079745;8081.919180808117;8115.75652730419;8302;7469.477136600603;7732.922670773445;7974.5215287082965;8164.673413063411;8345.998480182418 16:34:24 INFO - PROCESS | 1671 | |3;mootools.html;1453.825540935088;1496.8128983877016;1501.7972573702548;1812.2116879157559;1842.1710230396316;8041.798955026127;8463.280621147202;8466.322694184466;8469.74590762278;8490.193431623986;7407.111146662408;7713.961430192855;8058.39562032847;8301.792455188623;8304.294134998518;1985.821276085152;2246.180144247346;2256.435891027243;2300.2639155470256;2303.332033710222;2268.319504148749;2304.158981971577;2336.4742932840145;2337.742848286687;2338.2985104468676;2247.820174386053;2286.599845027122;2308.203669733939;2315.0623997281045;2325.3256555598855;2254.447660323215;2261.027758064032;2282.783135602115;2285.531466049456;2286.542691461706;2287.931362059141;2318.3392733070996;2319.3621754017613;2323.581755284065;2331.860088394702;7141.500094993353;8474.830503389898;8475;8475.23722864939;8488.74533763988;5031.572316353115;5189.325387699575;5192.532672059533;5193.558547523465;5197.948020519784;3255.869765209389;3321.2029113012945;3529.8764543240864;3602.7297952653657;3612.8916132516065;943.7499062748377;1057.762003549203;1058.037186160598;1083.2904447586843;1105.9944700276446;10726;10823.837642435292;10842.72893177669;10867.83698244527;10903.182261330352;4412.735235885857;4535.297028960516;4658.883527911829;4664.953350466452;4676.719396836201;9428.622855085858;9430.717078487656;9448.244140468745;9458;9477.526123693788;5057.873553161126;5082.1360368737405;5109.3613298337705;5152.92270615941;5164.561012313995;4221.113566151082;4467.084247729273;4729.716217026989;4737;4738.810447582058;3243.675632436785;3707.3141468828353;3740.8129593520216;3780.886573402747;3831.8084095795098;1245.3088535862503;1246.4827096754927;1255.0483997383974;1265.3989355056274;1272.2748033620746;745.7788914742531;766.6550052476408;872.6116877989234;891.8783513643551;894.8836651235298;5414.512693857571;5430.104032834617;5551.972240138854;5751.741171647286;5761.683107429131;840.537704262653;900.3112618846587;943.3632298198688;953.9952300238455;989.4014121456415;3689.1883785567134;3717.5724791648768;3733.7946412947003;3816;3831.8275677594575;10001.249906256915;10392.688219353431;10396.22028347877;10410.843837342445;10433.16534677224;10260.384376937407;10366.274360794672;10387.68836934893;10400.687979360631;10417.114545263586;7408.7036518539735;7648.655810488542;7857.05715314166;7870.803229919298;8038.799030024179;5481.040817856939;5495.093309604031;5552.472515111058;5632.070708333079;5723.055695810144;9322.860157097784;9416.717498475056;9426.670066547638;9460.432374057304;9610.27922905799;9434.103760142776;9522.047795220422;9587.089226523469;9625.470599117116;9635.325527213308;6646.667666616747;6903.0680858083515;6915.412189963909;7206.927930720625;7382.446316526175;7760.68957241704;8011.719589814328;8038.55787931646;8104.675812967415;8175.345972322319;7029.894551581628;7196.96401517989;7216.134063912364;7306.1963184048645;7318;6920.134983127109;6940.757073502403;6979.092717946837;7147.749828755968;7190.42476601881;6740.359665831738;6756.69594868242;6818.18181818185;6820.7271709133065;6837;4731.668783185281;4781.258904869751;4790.018046300431;4808.3027960946165;4895;4734.171519984058;4816.759162041954;4836.322914792002;4903.166461701448;4923.769057735566 16:34:24 INFO - PROCESS | 1671 | |4;prototype.html;277.8152528568502;296.44120832231243;303.80604225394205;310.82283098633775;310.99222519437023;5077.390713114422;5077.847664570068;5098.413682426518;5115.283860259567;5136.486351364862;4737.8815529611775;4898.53463920927;4902.460729319772;4906.9754651226785;5092.974535127329;3232.886848960287;3314.8342582870882;3785.413260944551;3788.4317352397225;3868.207017561393;3862.748921320105;3882.844686212562;3895.8831235062853;3907.2771537265553;3928.194720082376;3422.5550678411896;3846.307664620367;3861.691064714816;3876.2247550490015;3901.2977664020464;3853.903652408684;3888.8250028748776;3902.453656488094;3909.687225022006;3911.159100793343;3116.875324986998;3140.623125224965;3370.730341572693;3385.5937287525408;3389.271306669078;4844.66087373884;4981.850544483635;4983.6760610560195;5002.699838009731;5005.399352077774;3778.527684039495;4147.801665117492;4388.846390376353;4405.400813520038;4423.447069116361;4969.676970996909;4984.352034235562;5288.973555132238;5293.920591191135;5302.840914772563;3685.5208822853124;3687.8893633191046;3694.688385623092;3699.5190625218547;3708.5907355205118;5125.82059627915;5528.738044553733;5541.833744987657;5588.580856435743;5634.690092044936;3439.3465241604017;3666.2300916807235;3690.815459227028;3692.5015122958594;3835.520559930009;3479.7216222702123;3674.577423596267;3768.792716400624;3778.2065766188857;3790.2798468290935;3836.405357169643;4119.670426365884;4292.57300116162;4371.584699453547;4386.670999675037;4661.417322834645;5209.635325527177;5293.973530132402;5335.546478549367;5380.717762165076;1258.9055820813476;1284.2662405752346;1284.4284293489495;1284.9293288869014;1292.689754458923;741.8293792427772;809.688270016048;835.9007904605425;866.8613021916589;886.1492966751862;4987.807803006079;4997.175466048061;5012.649114562018;5018.5985121190215;5030.0945829751;4945.604351651859;5009.5491405773655;5175.301334319894;5274.235235890775;5485.708574855055;4092.7135100543273;4669.883252918704;4758.690685105457;5037.496250375007;5048.823291184791;2356.2342238772467;2497.675302210701;2903.8257704537796;2904.564315352714;2908.403777225664;4528.184926713157;4735.5319850846345;4874.561289483963;5493.423190564933;5718.285214348207;4938.456769755324;5254.15933450646;5605.794754127882;5651.943480565142;5668.971655141698;5435.967166238402;5707.885842283131;5717.485426311652;5736.368999410061;5753.367129615739;4654.464736555339;5073.568746656428;5215.765290561858;5432.782688692408;5524.447555244444;5158.664686795346;5444.863878403072;5451.511032939002;5470.64440811346;5555.944440555542;4776.5701086902345;5712;5721.799737009184;5730.596003979052;5758;3625.546806649169;3717.888463346104;3726.832292546788;3735.3089678409583;3744.138848065015;4763.54746299101;4798.592119669867;5409.918851217156;5479.53423958952;5482.492314613353;4202.873913782591;4433.268510695699;5327.733613319241;5380.650257733235;5412.242286079873;4446.7776611168665;5009.77456014473;5284.577233821362;5303.602229832855;5328.733563321896;4975.228839529878;5215.400228973716;5228.3335498896595;5235.973820131027;5263.947360526499;4289.721168124062;5204.453532379046;5246.895062098813;5370.516653501204;5388.2725832012175;4380.335472520422;5029.038674916712;5261.500157485033;5269.473052694853;5363.302770639792 16:34:24 INFO - PROCESS | 1671 | |5;yui.html;691.6438034412276;715.1239731329119;756.6519401075504;792.1523969352787;801.4069588504507;14973.251337433125;15153.772693409608;15182.39270429183;15187.77218341723;15213.391464341426;13410.994175436832;13508;13515.797263041037;13521.797173042387;13523.526676566322;662.1722846441947;682.938535531802;815.8490679224362;819.9877001844977;843.5529169540154;825.6738588257634;831.78789408701;838.2707044870971;839.4921072750959;841.5245386356689;890.9064548222441;891.1177933845479;891.8260939116842;892.134629409475;894.3739382432324;693.237438817302;712.5653351455608;887.7203680840547;888.8444522208653;888.866669999502;580.928187494077;644.5391545045316;644.983875403114;660.2769966886252;667.8063361625123;11869;14398.208098554574;14444.92777536116;14469.348879300456;14475.855241447556;13048.804267935988;13053.258845437616;13103.934480327538;13136.211827290394;13144.40850161745;11888.989435897958;11957.402129893471;11964.940175299154;12038.578649747304;12156.51373945041;4357.150355680644;4402.471703395613;4476.440444944382;4484.372187893698;4581.198290299184;75889.24110758876;77044.61477692632;77156.22843771602;77184;77457.22542774556;10149.543270552844;10260.384376937407;10420.218483613759;10508.63219787319;10891;19139;20010.099545520487;20077.799222007885;20135.295264665958;20327.593448130956;7454.180040195574;7498.85002299951;7564.621768911643;7586.696532138763;7705.998220231334;4279.2083464559155;4354.651627869763;4392.143532011291;4397.6921615487245;4476.552344765562;3046.131852422049;3326.9334613307597;3327.9168020799675;3332.866685332608;3342.9164270892934;602.286290745468;619.1115748900343;627.9686015699197;629.5184184099168;635.0505993539688;525.5217751845878;595.4373117404098;604.4137186928672;608.1759216261989;612.7824622259034;7383.547573222145;7799.805004874924;8342.748587711892;8542.210143130638;8851.60167792451;533.330670009136;585.844751140948;586.2946180141465;618.3569088148275;641.4996302883758;3573.0888623400865;4076.30702780528;4549.976255342522;4594.356790049395;4622.722636641813;99233;105296;105414;105541;107055;88951;90750;91699.08300917038;92034.6194807065;94797.52601236949;81414.59292703617;84684.57657711672;85198;85315;86786.83032924228;8795.340349473687;8796.296296296408;8828.073052329413;8944.68693595721;9043.740513298826;38433.807830960664;48836.511634883194;50273.98904043797;51619.741901290254;52431.47568524266;53250.4674953261;66693;68944.62110757656;74407;74578.88131677921;10709.732256693645;10806.189535785004;10860.348379097279;10999.065079468335;11126.888731112585;11733.237339572901;11767.823482647596;11780;11860.347680877634;12298.692532686755;11432;11547.942260288644;11679.06567474617;11802.996745276405;11959;11216.88783112191;11579.884201158218;11580.420978951188;11586.536538538365;11966.880331196924;11926.432491985426;12182.147249692436;12772.233665980071;12907.225566466042;13037.956963443092;10842.403667798348;11008.889911101107;11155.94422027917;11321.943390282995;11647.6505704829;10523.315984460985;10709.464526773785;11046.33721976684;11172.664820055412;11319.320840749582 16:34:24 INFO - PROCESS | 1671 | __end_tp_report 16:34:24 INFO - PROCESS | 1671 | __start_cc_report 16:34:24 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,3016 16:34:24 INFO - PROCESS | 1671 | __end_cc_report 16:34:24 INFO - PROCESS | 1671 | __startTimestamp1478216064968__endTimestamp 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:34:24 INFO - PROCESS | 1671 | Number of tests: 6 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5520.90 Median:5673.55 stddev:638.54 (11.3%) stddev-sans-first:602.63 16:34:24 INFO - PROCESS | 1671 | Values: 2759.6 3268.4 3287.9 3308.8 3352.5 5367.7 5393.8 5469.6 5491.9 5509.0 5433.6 5479.5 5554.8 5582.4 5608.7 5010.5 5031.0 5048.9 5060.0 5108.6 4285.8 5082.5 5167.5 5171.2 5180.8 4978.5 5136.3 5149.2 5160.1 5163.4 4999.3 5049.5 5071.4 5078.4 5101.6 4528.3 4792.7 5043.7 5051.5 5143.9 5482.9 5490.3 5503.6 5505.4 5530.9 5753.9 5826.7 5830.4 5904.7 5937.6 4897.6 5993.9 6030.4 6112.8 6119.0 5602.5 5611.0 5618.8 5622.9 5655.7 5020.5 6137.8 6202.7 6221.7 6236.2 5673.5 5745.7 5750.8 5833.1 5930.2 5495.4 6020.4 6065.2 6078.2 6103.6 5944.1 5954.6 5976.2 5990.1 5990.9 5827.7 5866.8 5968.1 5984.4 5987.2 4026.7 4747.3 4761.9 4767.2 4774.2 4209.9 4280.7 4311.7 4345.6 4368.9 5602.2 5619.2 5645.7 5655.6 5696.8 6061.9 6095.5 6122.7 6128.3 6130.4 4879.5 5383.9 5459.9 5599.5 5600.7 5190.7 5193.9 5196.6 5198.2 5206.9 6149.6 6214.0 6214.3 6218.8 6222.8 5997.4 6009.3 6010.7 6016.7 6016.7 6173.4 6212.7 6228.4 6229.8 6230.4 5729.5 5870.1 5885.5 5929.3 5944.1 5959.3 5980.9 5985.2 5987.7 6018.2 5724.5 5831.9 5880.1 5894.3 5940.8 5874.5 5896.9 5904.7 5916.6 5922.2 5551.2 5593.1 5597.8 5629.2 5663.7 5796.5 5856.9 5878.4 5890.6 5918.1 5692.6 5709.3 5713.8 5723.9 5750.4 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:65190.88 Median:39280.01 stddev:141707.67 (360.8%) stddev-sans-first:142021.84 16:34:24 INFO - PROCESS | 1671 | Values: 453.5 499.9 519.5 558.9 566.2 36873.3 38897.8 38964.0 39028.4 39067.8 39746.2 40370.8 40408.2 40438.0 40489.0 9260.5 9457.2 9464.5 9464.9 9466.9 9331.8 9433.7 9448.0 9455.4 9468.5 9429.5 9474.2 9478.2 9490.0 9498.8 9397.2 9450.4 9464.0 9467.3 9494.0 9292.1 9396.4 9403.6 9405.6 9414.5 37704.1 38135.6 38202.2 38225.6 38309.6 3266.7 3342.7 3434.7 3495.6 3741.9 27932.3 29649.1 30276.5 30297.8 30327.8 3229.3 3241.9 3246.6 3319.9 3355.3 303952.9 361562.0 365429.0 368937.0 368957.0 57466.0 57687.7 57722.7 57796.7 57906.7 60316.0 60352.8 60435.0 60442.4 60651.0 15213.6 15443.5 15443.8 15472.8 15530.4 11907.2 11980.9 11983.5 11983.9 12007.6 13712.9 13786.5 15523.8 16001.0 16289.2 6671.2 6847.3 6848.6 6849.9 6882.2 651.2 739.5 793.1 877.9 1043.3 27314.0 28110.4 28832.9 28909.7 29779.0 876.6 897.8 1044.9 1054.3 1070.2 14944.5 15160.9 15168.2 15291.6 15423.0 614492.0 621906.0 622152.0 622743.0 623082.0 47923.8 50081.7 50371.7 50481.7 50528.2 1231.8 1291.2 1319.1 1362.6 1365.6 27669.3 29190.6 29793.1 29845.6 30894.4 47137.0 47616.8 47667.5 47738.0 47799.5 504584.0 506275.0 607195.0 620374.0 623470.9 24831.0 25021.1 25086.5 25120.0 25142.6 44823.1 45061.9 45286.1 46842.5 48024.5 37801.4 37915.8 38489.1 38504.8 39380.0 38100.0 38145.8 38626.8 38800.4 39413.8 36908.6 37303.3 37856.6 39516.8 40395.0 39205.0 39355.0 39725.6 40768.5 41220.2 28861.9 29909.0 31438.0 32216.0 32277.2 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34659.98 Median:354.86 stddev:100195.04 (28235.1%) stddev-sans-first:100442.96 16:34:24 INFO - PROCESS | 1671 | Values: 329.7 348.2 361.5 363.1 372.5 14799.0 15544.8 18471.4 18650.9 19327.0 17443.0 17695.4 19451.5 20140.0 20346.3 5203.4 5746.9 6055.6 6125.5 6164.3 5695.5 6076.3 6120.0 6130.0 6152.1 5975.0 6052.6 6067.5 6068.1 6076.4 5828.7 6033.5 6062.5 6069.2 6128.8 5950.9 5956.6 5965.8 5973.3 5988.2 12930.7 13075.0 13165.7 13373.4 13373.8 2276.8 2431.9 2462.9 2702.0 2883.4 9711.6 9808.9 9835.1 10220.7 10303.8 1824.0 1833.2 1861.8 1947.9 1971.5 117070.4 125111.1 125503.4 126601.0 129611.4 9924.0 10949.5 11275.5 11392.5 11490.8 14731.0 14744.7 14762.9 14763.0 14782.6 8032.5 8344.7 8347.0 8438.9 8500.1 8194.1 8200.3 8200.5 8311.8 8321.2 4488.1 4502.6 4515.8 4524.2 4604.0 1862.8 1919.6 1920.1 1920.3 1921.0 687.8 761.6 803.8 865.4 953.6 19388.6 21115.7 21135.8 21229.4 21544.9 903.7 926.8 948.2 958.1 964.4 9172.8 10533.3 10995.5 11043.2 11062.0 589329.0 592290.0 596172.0 601948.0 612749.0 115047.0 119575.8 129876.1 137475.3 141180.0 824.8 1011.7 1027.7 1028.6 1031.6 14299.6 18543.6 19337.5 19374.1 19396.9 45224.6 49935.3 52192.0 53205.0 53884.0 72227.0 79335.6 79758.0 82056.2 82876.6 7655.6 7866.6 8001.3 8144.5 8337.7 12330.9 12614.0 12664.7 13003.7 13082.9 10793.3 11144.7 11297.1 11409.8 11444.9 10774.7 10838.3 10999.9 11258.0 11377.6 10681.0 11181.8 11722.3 11753.4 11843.3 7117.7 7972.9 8081.9 8115.8 8302.0 7469.5 7732.9 7974.5 8164.7 8346.0 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5461.49 Median:5070.00 stddev:3022.37 (59.6%) stddev-sans-first:3015.84 16:34:24 INFO - PROCESS | 1671 | Values: 1453.8 1496.8 1501.8 1812.2 1842.2 8041.8 8463.3 8466.3 8469.7 8490.2 7407.1 7714.0 8058.4 8301.8 8304.3 1985.8 2246.2 2256.4 2300.3 2303.3 2268.3 2304.2 2336.5 2337.7 2338.3 2247.8 2286.6 2308.2 2315.1 2325.3 2254.4 2261.0 2282.8 2285.5 2286.5 2287.9 2318.3 2319.4 2323.6 2331.9 7141.5 8474.8 8475.0 8475.2 8488.7 5031.6 5189.3 5192.5 5193.6 5197.9 3255.9 3321.2 3529.9 3602.7 3612.9 943.7 1057.8 1058.0 1083.3 1106.0 10726.0 10823.8 10842.7 10867.8 10903.2 4412.7 4535.3 4658.9 4665.0 4676.7 9428.6 9430.7 9448.2 9458.0 9477.5 5057.9 5082.1 5109.4 5152.9 5164.6 4221.1 4467.1 4729.7 4737.0 4738.8 3243.7 3707.3 3740.8 3780.9 3831.8 1245.3 1246.5 1255.0 1265.4 1272.3 745.8 766.7 872.6 891.9 894.9 5414.5 5430.1 5552.0 5751.7 5761.7 840.5 900.3 943.4 954.0 989.4 3689.2 3717.6 3733.8 3816.0 3831.8 10001.2 10392.7 10396.2 10410.8 10433.2 10260.4 10366.3 10387.7 10400.7 10417.1 7408.7 7648.7 7857.1 7870.8 8038.8 5481.0 5495.1 5552.5 5632.1 5723.1 9322.9 9416.7 9426.7 9460.4 9610.3 9434.1 9522.0 9587.1 9625.5 9635.3 6646.7 6903.1 6915.4 7206.9 7382.4 7760.7 8011.7 8038.6 8104.7 8175.3 7029.9 7197.0 7216.1 7306.2 7318.0 6920.1 6940.8 6979.1 7147.7 7190.4 6740.4 6756.7 6818.2 6820.7 6837.0 4731.7 4781.3 4790.0 4808.3 4895.0 4734.2 4816.8 4836.3 4903.2 4923.8 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4314.94 Median:4922.72 stddev:1318.25 (26.8%) stddev-sans-first:1286.65 16:34:24 INFO - PROCESS | 1671 | Values: 277.8 296.4 303.8 310.8 311.0 5077.4 5077.8 5098.4 5115.3 5136.5 4737.9 4898.5 4902.5 4907.0 5093.0 3232.9 3314.8 3785.4 3788.4 3868.2 3862.7 3882.8 3895.9 3907.3 3928.2 3422.6 3846.3 3861.7 3876.2 3901.3 3853.9 3888.8 3902.5 3909.7 3911.2 3116.9 3140.6 3370.7 3385.6 3389.3 4844.7 4981.9 4983.7 5002.7 5005.4 3778.5 4147.8 4388.8 4405.4 4423.4 4969.7 4984.4 5289.0 5293.9 5302.8 3685.5 3687.9 3694.7 3699.5 3708.6 5125.8 5528.7 5541.8 5588.6 5634.7 3439.3 3666.2 3690.8 3692.5 3835.5 3479.7 3674.6 3768.8 3778.2 3790.3 3836.4 4119.7 4292.6 4371.6 4386.7 4661.4 5209.6 5294.0 5335.5 5380.7 1258.9 1284.3 1284.4 1284.9 1292.7 741.8 809.7 835.9 866.9 886.1 4987.8 4997.2 5012.6 5018.6 5030.1 4945.6 5009.5 5175.3 5274.2 5485.7 4092.7 4669.9 4758.7 5037.5 5048.8 2356.2 2497.7 2903.8 2904.6 2908.4 4528.2 4735.5 4874.6 5493.4 5718.3 4938.5 5254.2 5605.8 5651.9 5669.0 5436.0 5707.9 5717.5 5736.4 5753.4 4654.5 5073.6 5215.8 5432.8 5524.4 5158.7 5444.9 5451.5 5470.6 5555.9 4776.6 5712.0 5721.8 5730.6 5758.0 3625.5 3717.9 3726.8 3735.3 3744.1 4763.5 4798.6 5409.9 5479.5 5482.5 4202.9 4433.3 5327.7 5380.7 5412.2 4446.8 5009.8 5284.6 5303.6 5328.7 4975.2 5215.4 5228.3 5236.0 5263.9 4289.7 5204.5 5246.9 5370.5 5388.3 4380.3 5029.0 5261.5 5269.5 5363.3 16:34:24 INFO - PROCESS | 1671 | 16:34:24 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:19445.26 Median:4517.17 stddev:28186.55 (624.0%) stddev-sans-first:28230.45 16:34:24 INFO - PROCESS | 1671 | Values: 691.6 715.1 756.7 792.2 801.4 14973.3 15153.8 15182.4 15187.8 15213.4 13411.0 13508.0 13515.8 13521.8 13523.5 662.2 682.9 815.8 820.0 843.6 825.7 831.8 838.3 839.5 841.5 890.9 891.1 891.8 892.1 894.4 693.2 712.6 887.7 888.8 888.9 580.9 644.5 645.0 660.3 667.8 11869.0 14398.2 14444.9 14469.3 14475.9 13048.8 13053.3 13103.9 13136.2 13144.4 11889.0 11957.4 11964.9 12038.6 12156.5 4357.2 4402.5 4476.4 4484.4 4581.2 75889.2 77044.6 77156.2 77184.0 77457.2 10149.5 10260.4 10420.2 10508.6 10891.0 19139.0 20010.1 20077.8 20135.3 20327.6 7454.2 7498.9 7564.6 7586.7 7706.0 4279.2 4354.7 4392.1 4397.7 4476.6 3046.1 3326.9 3327.9 3332.9 3342.9 602.3 619.1 628.0 629.5 635.1 525.5 595.4 604.4 608.2 612.8 7383.5 7799.8 8342.7 8542.2 8851.6 533.3 585.8 586.3 618.4 641.5 3573.1 4076.3 4550.0 4594.4 4622.7 99233.0 105296.0 105414.0 105541.0 107055.0 88951.0 90750.0 91699.1 92034.6 94797.5 81414.6 84684.6 85198.0 85315.0 86786.8 8795.3 8796.3 8828.1 8944.7 9043.7 38433.8 48836.5 50274.0 51619.7 52431.5 53250.5 66693.0 68944.6 74407.0 74578.9 10709.7 10806.2 10860.3 10999.1 11126.9 11733.2 11767.8 11780.0 11860.3 12298.7 11432.0 11547.9 11679.1 11803.0 11959.0 11216.9 11579.9 11580.4 11586.5 11966.9 11926.4 12182.1 12772.2 12907.2 13038.0 10842.4 11008.9 11155.9 11321.9 11647.7 10523.3 10709.5 11046.3 11172.7 11319.3 16:34:24 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:34:24 INFO - PROCESS | 1671 | 16:34:25 INFO - TEST-INFO | 1671: exit 0 16:34:25 INFO - TEST-OK | dromaeo_css | took 1119962ms 16:34:25 INFO - TEST-START | kraken 16:34:25 INFO - operating with platform_type : mac_ 16:34:25 INFO - Initialising browser for kraken test... 16:34:25 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpty5Lsh/profile http://localhost:49279/getInfo.html) 16:34:28 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 16:34:28 INFO - PROCESS | 1679 | colorDepth:24 16:34:28 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 16:34:28 INFO - PROCESS | 1679 | __metrics 16:34:28 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:34:28 INFO - PROCESS | 1679 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:34:28 INFO - TEST-INFO | 1679: exit 0 16:34:28 INFO - Browser initialized. 16:34:28 INFO - Running cycle 1/1 for kraken test... 16:34:28 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpty5Lsh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:41 INFO - PROCESS | 1681 | RSS: Main: 230993920 16:34:41 INFO - PROCESS | 1681 | 16:34:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:44 INFO - PROCESS | 1681 | RSS: Main: 290373632 16:34:44 INFO - PROCESS | 1681 | 16:34:47 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 16:34:47 INFO - PROCESS | 1681 | RSS: Main: 291508224 16:34:47 INFO - PROCESS | 1681 | 16:34:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 16:34:51 INFO - PROCESS | 1681 | RSS: Main: 384376832 16:34:51 INFO - PROCESS | 1681 | 16:34:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 16:34:54 INFO - PROCESS | 1681 | RSS: Main: 286961664 16:34:54 INFO - PROCESS | 1681 | 16:34:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:34:57 INFO - PROCESS | 1681 | RSS: Main: 288989184 16:34:57 INFO - PROCESS | 1681 | 16:35:00 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:00 INFO - PROCESS | 1681 | RSS: Main: 327356416 16:35:00 INFO - PROCESS | 1681 | 16:35:03 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:04 INFO - PROCESS | 1681 | RSS: Main: 326606848 16:35:04 INFO - PROCESS | 1681 | 16:35:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 16:35:07 INFO - PROCESS | 1681 | RSS: Main: 323534848 16:35:07 INFO - PROCESS | 1681 | 16:35:09 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:09 INFO - PROCESS | 1681 | RSS: Main: 302923776 16:35:09 INFO - PROCESS | 1681 | 16:35:11 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:12 INFO - PROCESS | 1681 | RSS: Main: 304758784 16:35:12 INFO - PROCESS | 1681 | 16:35:14 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:15 INFO - PROCESS | 1681 | RSS: Main: 309796864 16:35:15 INFO - PROCESS | 1681 | 16:35:18 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:19 INFO - PROCESS | 1681 | RSS: Main: 323616768 16:35:19 INFO - PROCESS | 1681 | 16:35:21 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:22 INFO - PROCESS | 1681 | RSS: Main: 316383232 16:35:22 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 16:35:24 INFO - PROCESS | 1681 | RSS: Main: 360099840 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | __start_tp_report 16:35:24 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 16:35:24 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 16:35:24 INFO - PROCESS | 1681 | |i|pagename|runs| 16:35:24 INFO - PROCESS | 1681 | |0;ai-astar;112;114;114;114;114;114;113;114;114;114 16:35:24 INFO - PROCESS | 1681 | |1;audio-beat-detection;148;112;113;149;116;110;112;115;146;112 16:35:24 INFO - PROCESS | 1681 | |2;audio-dft;185;186;187;185;184;188;180;182;185;185 16:35:24 INFO - PROCESS | 1681 | |3;audio-fft;85;59;59;89;63;59;75;82;98;58 16:35:24 INFO - PROCESS | 1681 | |4;audio-oscillator;87;72;97;70;70;69;89;70;69;93 16:35:24 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;95;89;122;91;94;87;95;90;94;87 16:35:24 INFO - PROCESS | 1681 | |6;imaging-darkroom;99;99;100;99;98;99;100;99;98;99 16:35:24 INFO - PROCESS | 1681 | |7;imaging-desaturate;81;83;78;83;81;84;86;84;81;81 16:35:24 INFO - PROCESS | 1681 | |8;json-parse-financial;57;56;54;55;55;55;56;55;56;56 16:35:24 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;71;67;67;81;74;72;69;70;68;69 16:35:24 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;86;85;90;88;90;87;86;86;88;88 16:35:24 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;148;150;151;159;138;154;132;154;214;143 16:35:24 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;174;176;172;173;175;174;174;170;175;174 16:35:24 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;68;67;67;68;78;78;83;84;75 16:35:24 INFO - PROCESS | 1681 | __end_tp_report 16:35:24 INFO - PROCESS | 1681 | __start_cc_report 16:35:24 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2099 16:35:24 INFO - PROCESS | 1681 | __end_cc_report 16:35:24 INFO - PROCESS | 1681 | __startTimestamp1478216124695__endTimestamp 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | ------- Summary: start ------- 16:35:24 INFO - PROCESS | 1681 | Number of tests: 14 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.33 16:35:24 INFO - PROCESS | 1681 | Values: 112.0 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:115.50 stddev:16.91 (14.6%) stddev-sans-first:15.40 16:35:24 INFO - PROCESS | 1681 | Values: 148.0 112.0 113.0 149.0 116.0 110.0 112.0 115.0 146.0 112.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:2.31 (1.2%) stddev-sans-first:2.45 16:35:24 INFO - PROCESS | 1681 | Values: 185.0 186.0 187.0 185.0 184.0 188.0 180.0 182.0 185.0 185.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:72.70 Median:78.50 stddev:14.99 (19.1%) stddev-sans-first:15.22 16:35:24 INFO - PROCESS | 1681 | Values: 85.0 59.0 59.0 89.0 63.0 59.0 75.0 82.0 98.0 58.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:79.50 stddev:11.42 (14.4%) stddev-sans-first:11.70 16:35:24 INFO - PROCESS | 1681 | Values: 87.0 72.0 97.0 70.0 70.0 69.0 89.0 70.0 69.0 93.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:92.50 stddev:10.18 (11.0%) stddev-sans-first:10.79 16:35:24 INFO - PROCESS | 1681 | Values: 95.0 89.0 122.0 91.0 94.0 87.0 95.0 90.0 94.0 87.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:99.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:35:24 INFO - PROCESS | 1681 | Values: 99.0 99.0 100.0 99.0 98.0 99.0 100.0 99.0 98.0 99.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:82.20 Median:83.00 stddev:2.25 (2.7%) stddev-sans-first:2.35 16:35:24 INFO - PROCESS | 1681 | Values: 81.0 83.0 78.0 83.0 81.0 84.0 86.0 84.0 81.0 81.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.85 (1.5%) stddev-sans-first:0.71 16:35:24 INFO - PROCESS | 1681 | Values: 57.0 56.0 54.0 55.0 55.0 55.0 56.0 55.0 56.0 56.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:4.21 (6.0%) stddev-sans-first:4.47 16:35:24 INFO - PROCESS | 1681 | Values: 71.0 67.0 67.0 81.0 74.0 72.0 69.0 70.0 68.0 69.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:88.00 stddev:1.71 (1.9%) stddev-sans-first:1.74 16:35:24 INFO - PROCESS | 1681 | Values: 86.0 85.0 90.0 88.0 90.0 87.0 86.0 86.0 88.0 88.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:152.50 stddev:22.47 (14.7%) stddev-sans-first:23.72 16:35:24 INFO - PROCESS | 1681 | Values: 148.0 150.0 151.0 159.0 138.0 154.0 132.0 154.0 214.0 143.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:174.00 stddev:1.70 (1.0%) stddev-sans-first:1.80 16:35:24 INFO - PROCESS | 1681 | Values: 174.0 176.0 172.0 173.0 175.0 174.0 174.0 170.0 175.0 174.0 16:35:24 INFO - PROCESS | 1681 | 16:35:24 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:76.50 stddev:6.82 (8.9%) stddev-sans-first:6.92 16:35:24 INFO - PROCESS | 1681 | Values: 68.0 68.0 67.0 67.0 68.0 78.0 78.0 83.0 84.0 75.0 16:35:24 INFO - PROCESS | 1681 | -------- Summary: end -------- 16:35:24 INFO - PROCESS | 1681 | 16:35:25 INFO - TEST-INFO | 1681: exit 0 16:35:25 INFO - TEST-OK | kraken | took 60013ms 16:35:25 INFO - SUITE-END | took 1179s 16:35:25 INFO - Completed test suite (00:19:40) 16:35:25 INFO - javascript benchmark 16:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2759.558470644697, 3268.3626692794915, 3287.9013629591122, 3308.768386212966, 3352.5306457095994, 5367.677939323643, 5393.757280922358, 5469.589780766439, 5491.862703432416, 5509.0, 5433.646812957155, 5479.4520547945185, 5554.777808887639, 5582.358028826686, 5608.719564021792, 5010.498950104979, 5031.044101620699, 5048.899022019557, 5060.0, 5108.616853735966, 4285.849995250167, 5082.491750824906, 5167.534921857032, 5171.224316352558, 5180.818671346504, 4978.452370239271, 5136.280920671109, 5149.201873709581, 5160.096983027974, 5163.380394352683, 4999.325091112726, 5049.5455409013, 5071.366079240095, 5078.390593128848, 5101.591872650179, 4528.343390208402, 4792.736399498026, 5043.697378157322, 5051.520105589963, 5143.9454911743005, 5482.890342193174, 5490.3411590608985, 5503.587230957654, 5505.394406615269, 5530.889382212374, 5753.942460575383, 5826.737796799154, 5830.387809280007, 5904.675242861641, 5937.584369094165, 4897.608191344684, 5993.940060599425, 6030.427109424598, 6112.847178820521, 6119.0, 5602.523785478198, 5610.971945140248, 5618.8033418830555, 5622.887542249214, 5655.717214139277, 5020.523050310215, 6137.7851775187655, 6202.689865506707, 6221.688915554204, 6236.220472440945, 5673.546116310686, 5745.68398738065, 5750.827475175752, 5833.125031245348, 5930.169776231331, 5495.395506494282, 6020.4280593343565, 6065.150878876961, 6078.209832721806, 6103.6032657877095, 5944.108383742473, 5954.55340849438, 5976.223090998143, 5990.101484777231, 5990.9101363479585, 5827.708614569255, 5866.765329386777, 5968.134620480093, 5984.371640977722, 5987.161797348374, 4026.7382620129597, 4747.2879068140055, 4761.857144285677, 4767.213409787346, 4774.236122220428, 4209.915801683949, 4280.6789490788315, 4311.676624253194, 4345.587169218919, 4368.934465983013, 5602.243697100921, 5619.157126431068, 5645.745941432563, 5655.632383895034, 5696.8006119785605, 6061.878762424727, 6095.542834287447, 6122.693865306717, 6128.264608247039, 6130.417610327012, 4879.463259041502, 5383.946160538423, 5459.890802183934, 5599.496045355938, 5600.747966341524, 5190.688558686491, 5193.948060519347, 5196.58427325813, 5198.16829307309, 5206.895862082737, 6149.569530133027, 6213.974694175501, 6214.347493513206, 6218.844528886814, 6222.782202622796, 5997.430244126801, 6009.278886533469, 6010.669413182317, 6016.6690832002905, 6016.699165041818, 6173.351798061139, 6212.720427580679, 6228.377162283736, 6229.750809967732, 6230.439260466398, 5729.455701708331, 5870.14882841994, 5885.499732522783, 5929.288485381781, 5944.108383742473, 5959.28488581373, 5980.910286345621, 5985.162077309267, 5987.700614969322, 6018.1875446815875, 5724.4561766632105, 5831.883362332815, 5880.117982302518, 5894.263217097863, 5940.79207227745, 5874.530037597067, 5896.941030589639, 5904.704764761659, 5916.615419997686, 5922.170896074639, 5551.167324901297, 5593.105103183471, 5597.776088956559, 5629.211910332475, 5663.716814159358, 5796.507296879812, 5856.912146317723, 5878.412158784087, 5890.617109887844, 5918.082697181943, 5692.6299790513485, 5709.286339207599, 5713.8285851424525, 5723.8569035774435, 5750.367459579526], "value": 5479.997979573086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.5328611530124, 499.8825276060125, 519.4934938435028, 558.8770470496491, 566.2129639800676, 36873.26253474929, 38897.80551097244, 38964.0, 39028.414573781345, 39067.82796516102, 39746.20507589846, 40370.79814600923, 40408.191836163256, 40437.98905027368, 40489.0, 9260.490673012982, 9457.243420526342, 9464.479453630047, 9464.858027129598, 9466.857997130048, 9331.813363732721, 9433.669821556246, 9448.0, 9455.385399949017, 9468.479233642147, 9429.528523573828, 9474.242060635168, 9478.194353479963, 9489.952550237274, 9498.762530936747, 9397.248220142372, 9450.385724927926, 9464.0, 9467.337286389955, 9494.0, 9292.117248861347, 9396.436213827124, 9403.62385504579, 9405.57674904631, 9414.482203478807, 37704.05739856498, 38135.61864381376, 38202.23595528074, 38225.617743822484, 38309.61690383089, 3266.673332666738, 3342.715869151125, 3434.6565343465704, 3495.563054618173, 3741.8690345837763, 27932.301692457648, 29649.110526684235, 30276.54585181224, 30297.848510757307, 30327.848360758057, 3229.337985712923, 3241.918952026195, 3246.626637936644, 3319.850606722703, 3355.312161006988, 303952.92682561127, 361562.0, 365428.97566779895, 368937.0, 368957.0, 57466.0, 57687.71156144192, 57722.71138644364, 57796.711016445486, 57906.71046644824, 60316.0, 60352.79294414087, 60435.0, 60442.39557604455, 60651.0, 15213.6196595086, 15443.536693899201, 15443.845561544465, 15472.845271547141, 15530.378784848479, 11907.226030308002, 11980.940095299467, 11983.46074426653, 11983.940080299717, 12007.579734709418, 13712.862871371359, 13786.517471888435, 15523.844761552466, 16001.0, 16289.185540722916, 6671.199456065303, 6847.349501797321, 6848.596037812236, 6849.897251541231, 6882.242953275236, 651.2185377546963, 739.5340935210891, 793.1434051224663, 877.863931090682, 1043.3062013760764, 27314.0, 28110.43779124406, 28832.855835720686, 28909.710902890703, 29779.0, 876.6186708781573, 897.8428774964356, 1044.9268551201494, 1054.3094273251033, 1070.1706177712335, 14944.476943306932, 15160.862935279898, 15168.24158792056, 15291.61770955735, 15423.00675558131, 614492.0, 621906.0, 622152.0, 622743.0, 623082.0, 47923.81142828603, 50081.74959125181, 50371.74814125906, 50481.670524363806, 50528.242076368886, 1231.8398608180878, 1291.2446218962014, 1319.0634649398842, 1362.6389006913373, 1365.5766712319212, 27669.30826729348, 29190.56214156832, 29793.106206812965, 29845.5523167157, 30894.38211235808, 47137.0, 47616.761916191586, 47667.523324766305, 47738.0, 47799.52200477951, 504584.0, 506275.0, 607195.0, 620374.0, 623470.8826455838, 24831.0, 25021.12426065152, 25086.49827003413, 25120.0, 25142.62286065747, 44823.10353792971, 45061.873453163935, 45286.09427811491, 46842.53157468518, 48024.51975480201, 37801.432978504796, 37915.81042094772, 38489.10614944025, 38504.80747596244, 39380.0, 38100.0, 38145.809270954574, 38626.80686596549, 38800.41799372955, 39413.80293098516, 36908.630913691595, 37303.25393492169, 37856.621433786415, 39516.80241598774, 40395.0, 39205.0, 39355.01612459712, 39725.60274397219, 40768.45783530056, 41220.1755964873, 28861.85569072141, 29909.0, 31438.0, 32216.0, 32277.193070173435], "value": 19240.386300588925, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.6818570079872, 348.19393104961995, 361.5264004154558, 363.13392558747387, 372.4580735030531, 14798.964072514927, 15544.844551554495, 18471.445856624305, 18650.906745466255, 19327.0, 17442.953422794642, 17695.380661676845, 19451.513712157204, 20140.0, 20346.28787992417, 5203.375594928614, 5746.942530574704, 6055.606385584945, 6125.540584456161, 6164.291106522763, 5695.5443564514735, 6076.3316035236085, 6120.0, 6130.0, 6152.107944348067, 5975.0, 6052.576319657625, 6067.453929146376, 6068.089786532011, 6076.422739839708, 5828.708564571797, 6033.547483938678, 6062.545309101813, 6069.211002569682, 6128.846778830521, 5950.8809823803285, 5956.583039187259, 5965.761369545213, 5973.283206015262, 5988.221531200959, 12930.741385172243, 13075.0, 13165.670858228525, 13373.398197081058, 13373.799393009112, 2276.817854571647, 2431.9270421887372, 2462.938426539333, 2701.986490067537, 2883.4377296427206, 9711.61153553857, 9808.852867206997, 9835.065668761456, 10220.74448138795, 10303.845442318372, 1823.972640410395, 1833.2025568877582, 1861.8324350808493, 1947.8831270123837, 1971.4578490914969, 117070.41464792707, 125111.12333315007, 125503.37248313791, 126601.0, 129611.35194324062, 9923.950380248052, 10949.507272172772, 11275.54897804095, 11392.544298228144, 11490.827637585442, 14730.968832181645, 14744.705105898036, 14762.852371476363, 14762.966592338647, 14782.560877455315, 8032.469632110588, 8344.707935222359, 8347.0, 8438.915610843813, 8500.14998500145, 8194.05768336637, 8200.26197642215, 8200.507969521848, 8311.792205194797, 8321.209485098905, 4488.102379524108, 4502.57225563571, 4515.774211289422, 4524.230880750215, 4603.976980115145, 1862.7950925398195, 1919.5680971781237, 1920.1359388275332, 1920.2990908318363, 1920.9807901920801, 687.7558615556383, 761.5621017914722, 803.7789607857769, 865.4158443050916, 953.6376177052814, 19388.612227755366, 21115.683264751042, 21135.78864211369, 21229.363119106452, 21544.892275538834, 903.7333986473875, 926.8470702334039, 948.231932134959, 958.0551480494468, 964.4213471916794, 9172.816543669223, 10533.262671612914, 10995.450227488594, 11043.226974111734, 11062.004419602274, 589329.0, 592290.0, 596172.0, 601948.0, 612749.0, 115047.0, 119575.80424195647, 129876.05185922219, 137475.31262343825, 141180.0, 824.8309096635176, 1011.6610935336597, 1027.7276521721749, 1028.5577201803146, 1031.63377001165, 14299.64250893736, 18543.629127417105, 19337.51656208606, 19374.128164232927, 19396.903015485397, 45224.64326070223, 49935.250971236186, 52191.95616087581, 53205.0, 53884.0, 72227.0, 79335.60332198303, 79758.0, 82056.17943820724, 82876.58561707153, 7655.57894315818, 7866.646000930086, 8001.279884810395, 8144.511329320259, 8337.708180213664, 12330.876691232972, 12614.0, 12664.746705065663, 13003.674908127372, 13082.934585327394, 10793.298435601975, 11144.721381965515, 11297.096232301561, 11409.771804564029, 11444.94277528607, 10774.676759697222, 10838.295510792088, 10999.890001099886, 11258.0, 11377.601783937522, 10681.0, 11181.776364472827, 11722.296662200295, 11753.353565553634, 11843.28940263587, 7117.683228602725, 7972.92027079745, 8081.919180808117, 8115.75652730419, 8302.0, 7469.477136600603, 7732.922670773445, 7974.5215287082965, 8164.673413063411, 8345.998480182418], "value": 9243.703128307492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.825540935088, 1496.8128983877016, 1501.7972573702548, 1812.2116879157559, 1842.1710230396316, 8041.798955026127, 8463.280621147202, 8466.322694184466, 8469.74590762278, 8490.193431623986, 7407.111146662408, 7713.961430192855, 8058.39562032847, 8301.792455188623, 8304.294134998518, 1985.821276085152, 2246.180144247346, 2256.435891027243, 2300.2639155470256, 2303.332033710222, 2268.319504148749, 2304.158981971577, 2336.4742932840145, 2337.742848286687, 2338.2985104468676, 2247.820174386053, 2286.599845027122, 2308.203669733939, 2315.0623997281045, 2325.3256555598855, 2254.447660323215, 2261.027758064032, 2282.783135602115, 2285.531466049456, 2286.542691461706, 2287.931362059141, 2318.3392733070996, 2319.3621754017613, 2323.581755284065, 2331.860088394702, 7141.500094993353, 8474.830503389898, 8475.0, 8475.23722864939, 8488.74533763988, 5031.572316353115, 5189.325387699575, 5192.532672059533, 5193.558547523465, 5197.948020519784, 3255.869765209389, 3321.2029113012945, 3529.8764543240864, 3602.7297952653657, 3612.8916132516065, 943.7499062748377, 1057.762003549203, 1058.037186160598, 1083.2904447586843, 1105.9944700276446, 10726.0, 10823.837642435292, 10842.72893177669, 10867.83698244527, 10903.182261330352, 4412.735235885857, 4535.297028960516, 4658.883527911829, 4664.953350466452, 4676.719396836201, 9428.622855085858, 9430.717078487656, 9448.244140468745, 9458.0, 9477.526123693788, 5057.873553161126, 5082.1360368737405, 5109.3613298337705, 5152.92270615941, 5164.561012313995, 4221.113566151082, 4467.084247729273, 4729.716217026989, 4737.0, 4738.810447582058, 3243.675632436785, 3707.3141468828353, 3740.8129593520216, 3780.886573402747, 3831.8084095795098, 1245.3088535862503, 1246.4827096754927, 1255.0483997383974, 1265.3989355056274, 1272.2748033620746, 745.7788914742531, 766.6550052476408, 872.6116877989234, 891.8783513643551, 894.8836651235298, 5414.512693857571, 5430.104032834617, 5551.972240138854, 5751.741171647286, 5761.683107429131, 840.537704262653, 900.3112618846587, 943.3632298198688, 953.9952300238455, 989.4014121456415, 3689.1883785567134, 3717.5724791648768, 3733.7946412947003, 3816.0, 3831.8275677594575, 10001.249906256915, 10392.688219353431, 10396.22028347877, 10410.843837342445, 10433.16534677224, 10260.384376937407, 10366.274360794672, 10387.68836934893, 10400.687979360631, 10417.114545263586, 7408.7036518539735, 7648.655810488542, 7857.05715314166, 7870.803229919298, 8038.799030024179, 5481.040817856939, 5495.093309604031, 5552.472515111058, 5632.070708333079, 5723.055695810144, 9322.860157097784, 9416.717498475056, 9426.670066547638, 9460.432374057304, 9610.27922905799, 9434.103760142776, 9522.047795220422, 9587.089226523469, 9625.470599117116, 9635.325527213308, 6646.667666616747, 6903.0680858083515, 6915.412189963909, 7206.927930720625, 7382.446316526175, 7760.68957241704, 8011.719589814328, 8038.55787931646, 8104.675812967415, 8175.345972322319, 7029.894551581628, 7196.96401517989, 7216.134063912364, 7306.1963184048645, 7318.0, 6920.134983127109, 6940.757073502403, 6979.092717946837, 7147.749828755968, 7190.42476601881, 6740.359665831738, 6756.69594868242, 6818.18181818185, 6820.7271709133065, 6837.0, 4731.668783185281, 4781.258904869751, 4790.018046300431, 4808.3027960946165, 4895.0, 4734.171519984058, 4816.759162041954, 4836.322914792002, 4903.166461701448, 4923.769057735566], "value": 4411.886447763976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.8152528568502, 296.44120832231243, 303.80604225394205, 310.82283098633775, 310.99222519437023, 5077.390713114422, 5077.847664570068, 5098.413682426518, 5115.283860259567, 5136.486351364862, 4737.8815529611775, 4898.53463920927, 4902.460729319772, 4906.9754651226785, 5092.974535127329, 3232.886848960287, 3314.8342582870882, 3785.413260944551, 3788.4317352397225, 3868.207017561393, 3862.748921320105, 3882.844686212562, 3895.8831235062853, 3907.2771537265553, 3928.194720082376, 3422.5550678411896, 3846.307664620367, 3861.691064714816, 3876.2247550490015, 3901.2977664020464, 3853.903652408684, 3888.8250028748776, 3902.453656488094, 3909.687225022006, 3911.159100793343, 3116.875324986998, 3140.623125224965, 3370.730341572693, 3385.5937287525408, 3389.271306669078, 4844.66087373884, 4981.850544483635, 4983.6760610560195, 5002.699838009731, 5005.399352077774, 3778.527684039495, 4147.801665117492, 4388.846390376353, 4405.400813520038, 4423.447069116361, 4969.676970996909, 4984.352034235562, 5288.973555132238, 5293.920591191135, 5302.840914772563, 3685.5208822853124, 3687.8893633191046, 3694.688385623092, 3699.5190625218547, 3708.5907355205118, 5125.82059627915, 5528.738044553733, 5541.833744987657, 5588.580856435743, 5634.690092044936, 3439.3465241604017, 3666.2300916807235, 3690.815459227028, 3692.5015122958594, 3835.520559930009, 3479.7216222702123, 3674.577423596267, 3768.792716400624, 3778.2065766188857, 3790.2798468290935, 3836.405357169643, 4119.670426365884, 4292.57300116162, 4371.584699453547, 4386.670999675037, 4661.417322834645, 5209.635325527177, 5293.973530132402, 5335.546478549367, 5380.717762165076, 1258.9055820813476, 1284.2662405752346, 1284.4284293489495, 1284.9293288869014, 1292.689754458923, 741.8293792427772, 809.688270016048, 835.9007904605425, 866.8613021916589, 886.1492966751862, 4987.807803006079, 4997.175466048061, 5012.649114562018, 5018.5985121190215, 5030.0945829751, 4945.604351651859, 5009.5491405773655, 5175.301334319894, 5274.235235890775, 5485.708574855055, 4092.7135100543273, 4669.883252918704, 4758.690685105457, 5037.496250375007, 5048.823291184791, 2356.2342238772467, 2497.675302210701, 2903.8257704537796, 2904.564315352714, 2908.403777225664, 4528.184926713157, 4735.5319850846345, 4874.561289483963, 5493.423190564933, 5718.285214348207, 4938.456769755324, 5254.15933450646, 5605.794754127882, 5651.943480565142, 5668.971655141698, 5435.967166238402, 5707.885842283131, 5717.485426311652, 5736.368999410061, 5753.367129615739, 4654.464736555339, 5073.568746656428, 5215.765290561858, 5432.782688692408, 5524.447555244444, 5158.664686795346, 5444.863878403072, 5451.511032939002, 5470.64440811346, 5555.944440555542, 4776.5701086902345, 5712.0, 5721.799737009184, 5730.596003979052, 5758.0, 3625.546806649169, 3717.888463346104, 3726.832292546788, 3735.3089678409583, 3744.138848065015, 4763.54746299101, 4798.592119669867, 5409.918851217156, 5479.53423958952, 5482.492314613353, 4202.873913782591, 4433.268510695699, 5327.733613319241, 5380.650257733235, 5412.242286079873, 4446.7776611168665, 5009.77456014473, 5284.577233821362, 5303.602229832855, 5328.733563321896, 4975.228839529878, 5215.400228973716, 5228.3335498896595, 5235.973820131027, 5263.947360526499, 4289.721168124062, 5204.453532379046, 5246.895062098813, 5370.516653501204, 5388.2725832012175, 4380.335472520422, 5029.038674916712, 5261.500157485033, 5269.473052694853, 5363.302770639792], "value": 3895.8802238130293, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.6438034412276, 715.1239731329119, 756.6519401075504, 792.1523969352787, 801.4069588504507, 14973.251337433125, 15153.772693409608, 15182.39270429183, 15187.77218341723, 15213.391464341426, 13410.994175436832, 13508.0, 13515.797263041037, 13521.797173042387, 13523.526676566322, 662.1722846441947, 682.938535531802, 815.8490679224362, 819.9877001844977, 843.5529169540154, 825.6738588257634, 831.78789408701, 838.2707044870971, 839.4921072750959, 841.5245386356689, 890.9064548222441, 891.1177933845479, 891.8260939116842, 892.134629409475, 894.3739382432324, 693.237438817302, 712.5653351455608, 887.7203680840547, 888.8444522208653, 888.866669999502, 580.928187494077, 644.5391545045316, 644.983875403114, 660.2769966886252, 667.8063361625123, 11869.0, 14398.208098554574, 14444.92777536116, 14469.348879300456, 14475.855241447556, 13048.804267935988, 13053.258845437616, 13103.934480327538, 13136.211827290394, 13144.40850161745, 11888.989435897958, 11957.402129893471, 11964.940175299154, 12038.578649747304, 12156.51373945041, 4357.150355680644, 4402.471703395613, 4476.440444944382, 4484.372187893698, 4581.198290299184, 75889.24110758876, 77044.61477692632, 77156.22843771602, 77184.0, 77457.22542774556, 10149.543270552844, 10260.384376937407, 10420.218483613759, 10508.63219787319, 10891.0, 19139.0, 20010.099545520487, 20077.799222007885, 20135.295264665958, 20327.593448130956, 7454.180040195574, 7498.85002299951, 7564.621768911643, 7586.696532138763, 7705.998220231334, 4279.2083464559155, 4354.651627869763, 4392.143532011291, 4397.6921615487245, 4476.552344765562, 3046.131852422049, 3326.9334613307597, 3327.9168020799675, 3332.866685332608, 3342.9164270892934, 602.286290745468, 619.1115748900343, 627.9686015699197, 629.5184184099168, 635.0505993539688, 525.5217751845878, 595.4373117404098, 604.4137186928672, 608.1759216261989, 612.7824622259034, 7383.547573222145, 7799.805004874924, 8342.748587711892, 8542.210143130638, 8851.60167792451, 533.330670009136, 585.844751140948, 586.2946180141465, 618.3569088148275, 641.4996302883758, 3573.0888623400865, 4076.30702780528, 4549.976255342522, 4594.356790049395, 4622.722636641813, 99233.0, 105296.0, 105414.0, 105541.0, 107055.0, 88951.0, 90750.0, 91699.08300917038, 92034.6194807065, 94797.52601236949, 81414.59292703617, 84684.57657711672, 85198.0, 85315.0, 86786.83032924228, 8795.340349473687, 8796.296296296408, 8828.073052329413, 8944.68693595721, 9043.740513298826, 38433.807830960664, 48836.511634883194, 50273.98904043797, 51619.741901290254, 52431.47568524266, 53250.4674953261, 66693.0, 68944.62110757656, 74407.0, 74578.88131677921, 10709.732256693645, 10806.189535785004, 10860.348379097279, 10999.065079468335, 11126.888731112585, 11733.237339572901, 11767.823482647596, 11780.0, 11860.347680877634, 12298.692532686755, 11432.0, 11547.942260288644, 11679.06567474617, 11802.996745276405, 11959.0, 11216.88783112191, 11579.884201158218, 11580.420978951188, 11586.536538538365, 11966.880331196924, 11926.432491985426, 12182.147249692436, 12772.233665980071, 12907.225566466042, 13037.956963443092, 10842.403667798348, 11008.889911101107, 11155.94422027917, 11321.943390282995, 11647.6505704829, 10523.315984460985, 10709.464526773785, 11046.33721976684, 11172.664820055412, 11319.320840749582], "value": 7069.993846647802, "unit": "score"}], "name": "dromaeo_css", "value": 7007.927050938775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 113.0, 149.0, 116.0, 110.0, 112.0, 115.0, 146.0, 112.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 187.0, 185.0, 184.0, 188.0, 180.0, 182.0, 185.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 59.0, 89.0, 63.0, 59.0, 75.0, 82.0, 98.0, 58.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 97.0, 70.0, 70.0, 69.0, 89.0, 70.0, 69.0, 93.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 122.0, 91.0, 94.0, 87.0, 95.0, 90.0, 94.0, 87.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 100.0, 99.0, 98.0, 99.0, 100.0, 99.0, 98.0, 99.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 78.0, 83.0, 81.0, 84.0, 86.0, 84.0, 81.0, 81.0], "value": 82.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 54.0, 55.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 67.0, 81.0, 74.0, 72.0, 69.0, 70.0, 68.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 90.0, 88.0, 90.0, 87.0, 86.0, 86.0, 88.0, 88.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 151.0, 159.0, 138.0, 154.0, 132.0, 154.0, 214.0, 143.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 172.0, 173.0, 175.0, 174.0, 174.0, 170.0, 175.0, 174.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 68.0, 78.0, 78.0, 83.0, 84.0, 75.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1463.8999999999999, "alertThreshold": 2.0}]} 16:35:25 INFO - Return code: 0 16:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:35:25 INFO - # TBPL SUCCESS # 16:35:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:35:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:35:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:35:25 INFO - 2016-11-03 16:35:25.997 screenresolution[1685:10944] starting screenresolution argv=screenresolution get 16:35:26 INFO - 2016-11-03 16:35:26.000 screenresolution[1685:10944] Display 0: 1600x1200x32@60 16:35:26 INFO - 2016-11-03 16:35:26.006 screenresolution[1686:10947] starting screenresolution argv=screenresolution list 16:35:26 INFO - Available Modes on Display 0 16:35:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:35:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:35:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:35:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:35:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:35:26 INFO - Intel Iris: 16:35:26 INFO - Chipset Model: Intel Iris 16:35:26 INFO - Type: GPU 16:35:26 INFO - Bus: Built-In 16:35:26 INFO - VRAM (Dynamic, Max): 1536 MB 16:35:26 INFO - Vendor: Intel (0x8086) 16:35:26 INFO - Device ID: 0x0a2e 16:35:26 INFO - Revision ID: 0x0009 16:35:26 INFO - Displays: 16:35:26 INFO - Display: 16:35:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:35:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:35:26 INFO - Display Serial Number: 1600x1200 60 16:35:26 INFO - Main Display: Yes 16:35:26 INFO - Mirror: Off 16:35:26 INFO - Online: Yes 16:35:26 INFO - Rotation: Supported 16:35:26 INFO - Return code: 0 16:35:26 INFO - Running post-action listener: _resource_record_post_action 16:35:26 INFO - [mozharness: 2016-11-03 23:35:26.249803Z] Finished run-tests step (success) 16:35:26 INFO - Running post-run listener: _resource_record_post_run 16:35:26 INFO - Total resource usage - Wall time: 1216s; CPU: 54.0%; Read bytes: 33451008; Write bytes: 710162944; Read time: 314; Write time: 2026 16:35:26 INFO - TinderboxPrint: CPU usage
53.6% 16:35:26 INFO - TinderboxPrint: I/O read bytes / time
33,451,008 / 314 16:35:26 INFO - TinderboxPrint: I/O write bytes / time
710,162,944 / 2,026 16:35:26 INFO - TinderboxPrint: CPU idle
2,258.6 (46.4%) 16:35:26 INFO - TinderboxPrint: CPU system
976.1 (20.1%) 16:35:26 INFO - TinderboxPrint: CPU user
1,631.1 (33.5%) 16:35:26 INFO - TinderboxPrint: Swap in / out
436,019,200 / 0 16:35:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398942720; Write bytes: 393099264; Read time: 30536; Write time: 738 16:35:26 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31427584; Write bytes: 300626432; Read time: 292; Write time: 1247 16:35:26 INFO - Running post-run listener: _upload_blobber_files 16:35:26 INFO - Blob upload gear active. 16:35:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:35:26 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 16:35:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:35:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:35:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:35:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:35:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:35:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:29 INFO - (blobuploader) - INFO - Done attempting. 16:35:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:35:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:35:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:30 INFO - (blobuploader) - INFO - Done attempting. 16:35:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:35:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:32 INFO - (blobuploader) - INFO - Done attempting. 16:35:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:35:32 INFO - Return code: 0 16:35:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:35:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:35:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f61fca903a094d3d6ecf303dd7df9a46dfb78a05cafd5f8e792a9fa11bbad64cbfa796f7b7755a0f7693f05c959e93d88f5946eaaad9b0edc51393eddd6cb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/595bcd933e21c1cc110edd29fe3d8350166c9d8c205a57752aeda78049c5c39e7a50a96f7e44e522b46f33a14fa554dafa9754391a7e7af3f1a8d7b486d1b96e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75a443c876e7f50e20982de778b588ffada6c0307b9e390f637be69d1b9272dcd9ee25a8b073510a099a2322134ded4efac257fa777de9ab5131ec87ad9a0dba"} 16:35:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:35:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:35:32 INFO - Contents: 16:35:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f61fca903a094d3d6ecf303dd7df9a46dfb78a05cafd5f8e792a9fa11bbad64cbfa796f7b7755a0f7693f05c959e93d88f5946eaaad9b0edc51393eddd6cb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/595bcd933e21c1cc110edd29fe3d8350166c9d8c205a57752aeda78049c5c39e7a50a96f7e44e522b46f33a14fa554dafa9754391a7e7af3f1a8d7b486d1b96e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75a443c876e7f50e20982de778b588ffada6c0307b9e390f637be69d1b9272dcd9ee25a8b073510a099a2322134ded4efac257fa777de9ab5131ec87ad9a0dba"} 16:35:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:35:32 INFO - Copying logs to upload dir... 16:35:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:35:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.460474 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-03 16:35:32.764437) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 16:35:32.768395) ========= 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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f61fca903a094d3d6ecf303dd7df9a46dfb78a05cafd5f8e792a9fa11bbad64cbfa796f7b7755a0f7693f05c959e93d88f5946eaaad9b0edc51393eddd6cb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/595bcd933e21c1cc110edd29fe3d8350166c9d8c205a57752aeda78049c5c39e7a50a96f7e44e522b46f33a14fa554dafa9754391a7e7af3f1a8d7b486d1b96e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75a443c876e7f50e20982de778b588ffada6c0307b9e390f637be69d1b9272dcd9ee25a8b073510a099a2322134ded4efac257fa777de9ab5131ec87ad9a0dba"} build_url:https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/fCkVcQk6T2m2-hNy4hoc5A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f61fca903a094d3d6ecf303dd7df9a46dfb78a05cafd5f8e792a9fa11bbad64cbfa796f7b7755a0f7693f05c959e93d88f5946eaaad9b0edc51393eddd6cb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/595bcd933e21c1cc110edd29fe3d8350166c9d8c205a57752aeda78049c5c39e7a50a96f7e44e522b46f33a14fa554dafa9754391a7e7af3f1a8d7b486d1b96e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75a443c876e7f50e20982de778b588ffada6c0307b9e390f637be69d1b9272dcd9ee25a8b073510a099a2322134ded4efac257fa777de9ab5131ec87ad9a0dba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 16:35:32.802617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:35:32.803087) ========= 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.bSXNkrdSsr/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.LZPJCl2iFO/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005276 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 16:35:32.856241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 16:35:32.856670) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 16:35:32.857157) ========= ========= Total master_lag: 0.33 =========