builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0064 starttime: 1484976842.16 results: success (0) buildid: 20170120193239 builduid: f61be685cff64b71942b2efa273f0462 revision: 3209927fdbf97ab394f4506d10546a5debce1015 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.158895) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.159394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.159689) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.184440) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.184772) ========= 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.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 --2017-01-20 21:34:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 211M=0s 2017-01-20 21:34:02 (211 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.366760) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.367083) ========= 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.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.024147 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:02.415289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-20 21:34:02.415612) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3209927fdbf97ab394f4506d10546a5debce1015 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3209927fdbf97ab394f4506d10546a5debce1015 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 2017-01-20 21:34:02,502 truncating revision to first 12 chars 2017-01-20 21:34:02,502 Setting DEBUG logging. 2017-01-20 21:34:02,502 attempt 1/10 2017-01-20 21:34:02,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3209927fdbf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 21:34:04,014 unpacking tar archive at: mozilla-aurora-3209927fdbf9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.761983 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-20 21:34:04.206026) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:04.206914) ========= script_repo_revision: 3209927fdbf97ab394f4506d10546a5debce1015 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:04.207966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:04.208403) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 21:34:04.229106) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-20 21:34:04.229805) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:04 INFO - MultiFileLogger online at 20170120 21:34:04 in /builds/slave/test 21:34:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:34:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:34:04 INFO - {'append_to_log': False, 21:34:04 INFO - 'base_work_dir': '/builds/slave/test', 21:34:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:34:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:34:04 INFO - 'branch': 'Mozilla-Aurora', 21:34:04 INFO - 'buildbot_json_path': 'buildprops.json', 21:34:04 INFO - 'config_files': ('talos/mac_config.py',), 21:34:04 INFO - 'default_actions': ('clobber', 21:34:04 INFO - 'read-buildbot-config', 21:34:04 INFO - 'download-and-extract', 21:34:04 INFO - 'populate-webroot', 21:34:04 INFO - 'create-virtualenv', 21:34:04 INFO - 'install', 21:34:04 INFO - 'run-tests'), 21:34:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:34:04 INFO - 'download_minidump_stackwalk': True, 21:34:04 INFO - 'download_symbols': 'ondemand', 21:34:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:34:04 INFO - 'tooltool.py': '/tools/tooltool.py', 21:34:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:34:04 INFO - '/tools/misc-python/virtualenv.py')}, 21:34:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:34:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:34:04 INFO - 'installer_path': 'installer.exe', 21:34:04 INFO - 'log_level': 'info', 21:34:04 INFO - 'log_name': 'talos', 21:34:04 INFO - 'log_to_console': True, 21:34:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:34:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:34:04 INFO - 'opt_config_files': (), 21:34:04 INFO - 'pip_index': False, 21:34:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:34:04 INFO - 'cmd': ('bash', 21:34:04 INFO - '-c', 21:34:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:34:04 INFO - 'enabled': True, 21:34:04 INFO - 'halt_on_failure': False, 21:34:04 INFO - 'name': 'check_screen_resolution'},), 21:34:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:34:04 INFO - 'cmd': ('bash', 21:34:04 INFO - '-c', 21:34:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:34:04 INFO - 'enabled': True, 21:34:04 INFO - 'halt_on_failure': False, 21:34:04 INFO - 'name': 'check_screen_resolution'},), 21:34:04 INFO - 'run_cmd_checks_enabled': True, 21:34:04 INFO - 'sps_profile': False, 21:34:04 INFO - 'sps_profile_interval': 0, 21:34:04 INFO - 'suite': 'dromaeojs', 21:34:04 INFO - 'system_bits': '32', 21:34:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:34:04 INFO - 'title': 't-yosemite-r7-0064', 21:34:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:34:04 INFO - 'use_talos_json': True, 21:34:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:34:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:34:04 INFO - 'work_dir': 'build'} 21:34:04 INFO - [mozharness: 2017-01-21 05:34:04.446658Z] Running clobber step. 21:34:04 INFO - Running pre-action listener: _resource_record_pre_action 21:34:04 INFO - Running main action method: clobber 21:34:04 INFO - rmtree: /builds/slave/test/build 21:34:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:34:05 INFO - Running post-action listener: _resource_record_post_action 21:34:05 INFO - [mozharness: 2017-01-21 05:34:05.345370Z] Finished clobber step (success) 21:34:05 INFO - [mozharness: 2017-01-21 05:34:05.345605Z] Running read-buildbot-config step. 21:34:05 INFO - Running pre-action listener: _resource_record_pre_action 21:34:05 INFO - Running main action method: read_buildbot_config 21:34:05 INFO - Using buildbot properties: 21:34:05 INFO - { 21:34:05 INFO - "project": "", 21:34:05 INFO - "product": "firefox", 21:34:05 INFO - "script_repo_revision": "production", 21:34:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 21:34:05 INFO - "repository": "", 21:34:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 21:34:05 INFO - "buildid": "20170120193239", 21:34:05 INFO - "pgo_build": "False", 21:34:05 INFO - "basedir": "/builds/slave/test", 21:34:05 INFO - "buildnumber": 3, 21:34:05 INFO - "slavename": "t-yosemite-r7-0064", 21:34:05 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 21:34:05 INFO - "platform": "yosemite_r7", 21:34:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:34:05 INFO - "branch": "mozilla-aurora", 21:34:05 INFO - "repo_path": "releases/mozilla-aurora", 21:34:05 INFO - "revision": "3209927fdbf97ab394f4506d10546a5debce1015", 21:34:05 INFO - "stage_platform": "macosx64", 21:34:05 INFO - "builduid": "f61be685cff64b71942b2efa273f0462", 21:34:05 INFO - "slavebuilddir": "test" 21:34:05 INFO - } 21:34:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 21:34:05 INFO - Running post-action listener: _resource_record_post_action 21:34:05 INFO - [mozharness: 2017-01-21 05:34:05.351093Z] Finished read-buildbot-config step (success) 21:34:05 INFO - [mozharness: 2017-01-21 05:34:05.351296Z] Running download-and-extract step. 21:34:05 INFO - Running pre-action listener: _resource_record_pre_action 21:34:05 INFO - Running main action method: download_and_extract 21:34:05 INFO - mkdir: /builds/slave/test/build/tests 21:34:05 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')]} 21:34:05 INFO - trying https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 21:34:05 INFO - Downloading https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 21:34:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 21:34:06 INFO - Downloaded 1199 bytes. 21:34:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 21:34:06 INFO - Using the following test package requirements: 21:34:06 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 21:34:06 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 21:34:06 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 21:34:06 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'jsshell-mac.zip'], 21:34:06 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 21:34:06 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 21:34:06 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 21:34:06 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 21:34:06 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 21:34:06 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 21:34:06 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 21:34:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:34:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 21:34:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:34:06 INFO - Fetch https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 21:34:07 INFO - Http code: 200 21:34:07 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 21:34:07 INFO - x-amz-cf-id: 1CnVjPzWXx-Lza_0aY-iaac7UC0JnMV9M0xE4M96YUSEVWPgEpC7mQ== 21:34:07 INFO - x-amz-version-id: GJl8vtMQCLG5H8wBXTDZft9FfP2KJiKp 21:34:07 INFO - Expected file size: 16277523 21:34:07 INFO - Obtained file size: 16277523 21:34:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 21:34:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 21:34:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:34:14 INFO - Fetch https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 21:34:15 INFO - Http code: 200 21:34:15 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 21:34:15 INFO - x-amz-cf-id: Ot6FGqF2lexUG1BMOFO2YFOPemsTAJDUmNQKDh9VN9U1TPZSknua2Q== 21:34:15 INFO - x-amz-version-id: GJl8vtMQCLG5H8wBXTDZft9FfP2KJiKp 21:34:15 INFO - Expected file size: 16277523 21:34:15 INFO - Obtained file size: 16277523 21:34:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 21:34:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:34:22 INFO - Fetch https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 21:34:23 INFO - Http code: 200 21:34:23 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 21:34:23 INFO - x-amz-cf-id: Cjj8jembPDvYirpWj5gRUb2yNGf2YFGV9sphxlFUPerxZwVCW5jgLA== 21:34:23 INFO - x-amz-version-id: J.JeTVIAh8w.jw8jSb63M_FoXmwaKgGU 21:34:23 INFO - Expected file size: 14402992 21:34:23 INFO - Obtained file size: 14402992 21:34:23 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')]} 21:34:23 INFO - trying https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:34:23 INFO - Downloading https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 21:34:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 21:34:27 INFO - Downloaded 110609010 bytes. 21:34:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:34:27 INFO - mkdir: /builds/slave/test/properties 21:34:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:34:27 INFO - Writing to file /builds/slave/test/properties/build_url 21:34:27 INFO - Contents: 21:34:27 INFO - build_url:https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 21:34:27 INFO - Running post-action listener: _resource_record_post_action 21:34:27 INFO - Running post-action listener: set_extra_try_arguments 21:34:27 INFO - [mozharness: 2017-01-21 05:34:27.774158Z] Finished download-and-extract step (success) 21:34:27 INFO - [mozharness: 2017-01-21 05:34:27.774297Z] Running populate-webroot step. 21:34:27 INFO - Running pre-action listener: _resource_record_pre_action 21:34:27 INFO - Running main action method: populate_webroot 21:34:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:34:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:34:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:34:27 INFO - '--tppagecycles', 21:34:27 INFO - '7'], 21:34:27 INFO - 'tests': ['tsvgm']}}, 21:34:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:34:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:34:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:34:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:34:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:34:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:34:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['damp', 'tps']}, 21:34:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'tests': ['damp', 'tps']}, 21:34:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['dromaeo_dom']}, 21:34:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:34:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['basic_compositor_video']}, 21:34:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:34:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'other-e10s': {'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['a11yr', 21:34:27 INFO - 'ts_paint', 21:34:27 INFO - 'tpaint', 21:34:27 INFO - 'sessionrestore', 21:34:27 INFO - 'sessionrestore_no_auto_restore', 21:34:27 INFO - 'tabpaint']}, 21:34:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['tsvgx', 21:34:27 INFO - 'tsvgr_opacity', 21:34:27 INFO - 'tart', 21:34:27 INFO - 'tscrollx', 21:34:27 INFO - 'cart']}, 21:34:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:34:27 INFO - 'tsvgr_opacity', 21:34:27 INFO - 'tart', 21:34:27 INFO - 'tscrollx', 21:34:27 INFO - 'cart']}, 21:34:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'talos_options': ['--disable-e10s'], 21:34:27 INFO - 'tests': ['tp5o']}, 21:34:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'tests': ['tp5o']}, 21:34:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'talos_options': ['--disable-e10s', 21:34:27 INFO - '--xperf_path', 21:34:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:34:27 INFO - 'tests': ['tp5n']}, 21:34:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:27 INFO - 'talos_options': ['--xperf_path', 21:34:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:34:27 INFO - 'tests': ['tp5n']}}, 21:34:27 INFO - 'talos.zip': {'path': '', 21:34:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:34:27 INFO - Running post-action listener: _resource_record_post_action 21:34:27 INFO - [mozharness: 2017-01-21 05:34:27.793349Z] Finished populate-webroot step (success) 21:34:27 INFO - [mozharness: 2017-01-21 05:34:27.793479Z] Running create-virtualenv step. 21:34:27 INFO - Running pre-action listener: _resource_record_pre_action 21:34:27 INFO - Running main action method: create_virtualenv 21:34:27 INFO - Creating virtualenv /builds/slave/test/build/venv 21:34:27 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 21:34:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:34:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:34:27 INFO - Using real prefix '/tools/python27' 21:34:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:34:28 INFO - Installing distribute.............................................................................................................................................................................................done. 21:34:31 INFO - Installing pip.................done. 21:34:31 INFO - Return code: 0 21:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:34:31 INFO - Reading from file tmpfile_stdout 21:34:31 INFO - Output received: 21:34:31 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) 21:34:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:31 INFO - 'HOME': '/Users/cltbld', 21:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:31 INFO - 'LOGNAME': 'cltbld', 21:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:31 INFO - 'MOZ_NO_REMOTE': '1', 21:34:31 INFO - 'NO_EM_RESTART': '1', 21:34:31 INFO - 'PAGER': '/bin/cat', 21:34:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:31 INFO - 'PWD': '/builds/slave/test', 21:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:31 INFO - 'SHELL': '/bin/bash', 21:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:31 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:31 INFO - 'USER': 'cltbld', 21:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:31 INFO - 'XPC_FLAGS': '0x0', 21:34:31 INFO - 'XPC_SERVICE_NAME': '0', 21:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:32 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 21:34:32 INFO - Cleaning up... 21:34:32 INFO - Return code: 0 21:34:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:32 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 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:32 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 21:34:32 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 21:34:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:32 INFO - 'HOME': '/Users/cltbld', 21:34:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:32 INFO - 'LOGNAME': 'cltbld', 21:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:32 INFO - 'MOZ_NO_REMOTE': '1', 21:34:32 INFO - 'NO_EM_RESTART': '1', 21:34:32 INFO - 'PAGER': '/bin/cat', 21:34:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:32 INFO - 'PWD': '/builds/slave/test', 21:34:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:32 INFO - 'SHELL': '/bin/bash', 21:34:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:32 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:32 INFO - 'USER': 'cltbld', 21:34:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:32 INFO - 'XPC_FLAGS': '0x0', 21:34:32 INFO - 'XPC_SERVICE_NAME': '0', 21:34:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:32 INFO - Downloading/unpacking psutil>=3.1.1 21:34:32 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 21:34:32 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 21:34:32 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 21:34:32 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 21:34:32 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 21:34:32 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 21:34:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:34:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:35 INFO - Installing collected packages: psutil 21:34:35 INFO - Running setup.py install for psutil 21:34:35 INFO - building 'psutil._psutil_osx' extension 21:34:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:34:36 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 21:34:36 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 21:34:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:34:36 INFO - building 'psutil._psutil_posix' extension 21:34:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:34:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:34:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:34:36 INFO - ^ 21:34:36 INFO - 1 warning generated. 21:34:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:34:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:36 INFO - Successfully installed psutil 21:34:36 INFO - Cleaning up... 21:34:36 INFO - Return code: 0 21:34:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:34:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:36 INFO - 'HOME': '/Users/cltbld', 21:34:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:36 INFO - 'LOGNAME': 'cltbld', 21:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:36 INFO - 'MOZ_NO_REMOTE': '1', 21:34:36 INFO - 'NO_EM_RESTART': '1', 21:34:36 INFO - 'PAGER': '/bin/cat', 21:34:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:36 INFO - 'PWD': '/builds/slave/test', 21:34:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:36 INFO - 'SHELL': '/bin/bash', 21:34:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:36 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:36 INFO - 'USER': 'cltbld', 21:34:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:36 INFO - 'XPC_FLAGS': '0x0', 21:34:36 INFO - 'XPC_SERVICE_NAME': '0', 21:34:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:34:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:34:39 INFO - Installing collected packages: mozsystemmonitor 21:34:39 INFO - Running setup.py install for mozsystemmonitor 21:34:39 INFO - Successfully installed mozsystemmonitor 21:34:39 INFO - Cleaning up... 21:34:39 INFO - Return code: 0 21:34:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:39 INFO - 'HOME': '/Users/cltbld', 21:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:39 INFO - 'LOGNAME': 'cltbld', 21:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:39 INFO - 'MOZ_NO_REMOTE': '1', 21:34:39 INFO - 'NO_EM_RESTART': '1', 21:34:39 INFO - 'PAGER': '/bin/cat', 21:34:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:39 INFO - 'PWD': '/builds/slave/test', 21:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:39 INFO - 'SHELL': '/bin/bash', 21:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:39 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:39 INFO - 'USER': 'cltbld', 21:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:39 INFO - 'XPC_FLAGS': '0x0', 21:34:39 INFO - 'XPC_SERVICE_NAME': '0', 21:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:40 INFO - Downloading/unpacking jsonschema==2.5.1 21:34:40 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 21:34:40 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 21:34:40 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 21:34:40 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 21:34:40 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 21:34:40 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 21:34:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:34:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:34: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 21:34: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 21:34: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 21:34: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 21:34: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 21:34: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 21:34:43 INFO - Downloading functools32-3.2.3-2.tar.gz 21:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:34:43 INFO - warning: no files found matching '*.txt' 21:34:43 INFO - no previously-included directories found matching 'build' 21:34:43 INFO - no previously-included directories found matching 'dist' 21:34:43 INFO - no previously-included directories found matching '.git*' 21:34:43 INFO - Installing collected packages: jsonschema, functools32 21:34:43 INFO - Running setup.py install for functools32 21:34:43 INFO - warning: no files found matching '*.txt' 21:34:43 INFO - no previously-included directories found matching 'build' 21:34:43 INFO - no previously-included directories found matching 'dist' 21:34:43 INFO - no previously-included directories found matching '.git*' 21:34:43 INFO - Successfully installed jsonschema functools32 21:34:43 INFO - Cleaning up... 21:34:43 INFO - Return code: 0 21:34:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:43 INFO - 'HOME': '/Users/cltbld', 21:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:43 INFO - 'LOGNAME': 'cltbld', 21:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:43 INFO - 'MOZ_NO_REMOTE': '1', 21:34:43 INFO - 'NO_EM_RESTART': '1', 21:34:43 INFO - 'PAGER': '/bin/cat', 21:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:43 INFO - 'PWD': '/builds/slave/test', 21:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:43 INFO - 'SHELL': '/bin/bash', 21:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:43 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:43 INFO - 'USER': 'cltbld', 21:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:43 INFO - 'XPC_FLAGS': '0x0', 21:34:43 INFO - 'XPC_SERVICE_NAME': '0', 21:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:34:43 INFO - Cleaning up... 21:34:43 INFO - Return code: 0 21:34:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:43 INFO - 'HOME': '/Users/cltbld', 21:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:43 INFO - 'LOGNAME': 'cltbld', 21:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:43 INFO - 'MOZ_NO_REMOTE': '1', 21:34:43 INFO - 'NO_EM_RESTART': '1', 21:34:43 INFO - 'PAGER': '/bin/cat', 21:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:43 INFO - 'PWD': '/builds/slave/test', 21:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:43 INFO - 'SHELL': '/bin/bash', 21:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:43 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:43 INFO - 'USER': 'cltbld', 21:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:43 INFO - 'XPC_FLAGS': '0x0', 21:34:43 INFO - 'XPC_SERVICE_NAME': '0', 21:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:44 INFO - Downloading/unpacking blobuploader==1.2.4 21:34:44 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 21:34:44 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 21:34:44 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 21:34:44 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 21:34:44 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 21:34:44 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 21:34:46 INFO - Downloading blobuploader-1.2.4.tar.gz 21:34:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:34:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:34:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:34: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 21:34: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 21:34: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 21:34: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 21:34: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 21:34: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 21:34:47 INFO - Downloading docopt-0.6.1.tar.gz 21:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:34:48 INFO - Installing collected packages: blobuploader, requests, docopt 21:34:48 INFO - Running setup.py install for blobuploader 21:34:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:34:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:34:48 INFO - Running setup.py install for requests 21:34:48 INFO - Running setup.py install for docopt 21:34:48 INFO - Successfully installed blobuploader requests docopt 21:34:48 INFO - Cleaning up... 21:34:48 INFO - Return code: 0 21:34:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:48 INFO - 'HOME': '/Users/cltbld', 21:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:48 INFO - 'LOGNAME': 'cltbld', 21:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:48 INFO - 'MOZ_NO_REMOTE': '1', 21:34:48 INFO - 'NO_EM_RESTART': '1', 21:34:48 INFO - 'PAGER': '/bin/cat', 21:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:48 INFO - 'PWD': '/builds/slave/test', 21:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:48 INFO - 'SHELL': '/bin/bash', 21:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:48 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:48 INFO - 'USER': 'cltbld', 21:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:48 INFO - 'XPC_FLAGS': '0x0', 21:34:48 INFO - 'XPC_SERVICE_NAME': '0', 21:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-RUvN3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ea4eBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Nx1nnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-eK1P_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-jMK3DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-RcpLwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-fURsTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:34:49 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-AZ6bLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-LfJOmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-yQWztf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-64hQuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-GLxzFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-v7DqWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-MKF2z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-2KxiVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-LhHquX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:34:50 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-uHz1mL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:34:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:34:50 INFO - Running setup.py install for manifestparser 21:34:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:34:51 INFO - Running setup.py install for mozcrash 21:34:51 INFO - Running setup.py install for mozdebug 21:34:51 INFO - Running setup.py install for mozdevice 21:34:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:34:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:34:51 INFO - Running setup.py install for mozfile 21:34:51 INFO - Running setup.py install for mozhttpd 21:34:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:34:51 INFO - Running setup.py install for mozinfo 21:34:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Running setup.py install for mozInstall 21:34:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Running setup.py install for mozleak 21:34:52 INFO - Running setup.py install for mozlog 21:34:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Running setup.py install for moznetwork 21:34:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Running setup.py install for mozprocess 21:34:52 INFO - Running setup.py install for mozprofile 21:34:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:34:52 INFO - Running setup.py install for mozrunner 21:34:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:34:53 INFO - Running setup.py install for mozscreenshot 21:34:53 INFO - Running setup.py install for moztest 21:34:53 INFO - Running setup.py install for mozversion 21:34:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:34:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:34:53 INFO - Cleaning up... 21:34:53 INFO - Return code: 0 21:34:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34: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')]} 21:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:34:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:34:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:53 INFO - 'HOME': '/Users/cltbld', 21:34:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:53 INFO - 'LOGNAME': 'cltbld', 21:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:53 INFO - 'MOZ_NO_REMOTE': '1', 21:34:53 INFO - 'NO_EM_RESTART': '1', 21:34:53 INFO - 'PAGER': '/bin/cat', 21:34:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:53 INFO - 'PWD': '/builds/slave/test', 21:34:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:53 INFO - 'SHELL': '/bin/bash', 21:34:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:53 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:53 INFO - 'USER': 'cltbld', 21:34:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:53 INFO - 'XPC_FLAGS': '0x0', 21:34:53 INFO - 'XPC_SERVICE_NAME': '0', 21:34:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:34:53 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-5eNyYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:34:54 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)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-YZCd9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-EcXQRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:34:54 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)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-aPLKb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:34:54 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)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-A1iML_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:34:54 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)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-mXrpSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QYQkVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-t_SIXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-2YdM0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:34:54 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-CYhzd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:34:55 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)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-WJ9D2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:34:55 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)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-O0T8UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:34:55 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)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-bxtZKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:34:55 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)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-STEU7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-2X9D7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-BxBovp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 21:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:34:55 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-zg65iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:34:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:58 INFO - Downloading blessings-1.6.tar.gz 21:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:34:58 INFO - Installing collected packages: blessings 21:34:58 INFO - Running setup.py install for blessings 21:34:58 INFO - Successfully installed blessings 21:34:58 INFO - Cleaning up... 21:34:58 INFO - Return code: 0 21:34:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:34:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:34:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:34:59 INFO - Reading from file tmpfile_stdout 21:34:59 INFO - Current package versions: 21:34:59 INFO - blessings == 1.6 21:34:59 INFO - blobuploader == 1.2.4 21:34:59 INFO - docopt == 0.6.1 21:34:59 INFO - functools32 == 3.2.3-2 21:34:59 INFO - jsonschema == 2.5.1 21:34:59 INFO - manifestparser == 1.1 21:34:59 INFO - mozInstall == 1.12 21:34:59 INFO - mozcrash == 1.0 21:34:59 INFO - mozdebug == 0.1 21:34:59 INFO - mozdevice == 0.48 21:34:59 INFO - mozfile == 1.2 21:34:59 INFO - mozhttpd == 0.7 21:34:59 INFO - mozinfo == 0.9 21:34:59 INFO - mozleak == 0.1 21:34:59 INFO - mozlog == 3.4 21:34:59 INFO - moznetwork == 0.27 21:34:59 INFO - mozprocess == 0.23 21:34:59 INFO - mozprofile == 0.28 21:34:59 INFO - mozrunner == 6.13 21:34:59 INFO - mozscreenshot == 0.1 21:34:59 INFO - mozsystemmonitor == 0.3 21:34:59 INFO - moztest == 0.8 21:34:59 INFO - mozversion == 1.4 21:34:59 INFO - psutil == 3.1.1 21:34:59 INFO - requests == 1.2.3 21:34:59 INFO - wsgiref == 0.1.2 21:34:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34: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')]} 21:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:59 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 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:34:59 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 21:34:59 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 21:34:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:34:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:59 INFO - 'HOME': '/Users/cltbld', 21:34:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:59 INFO - 'LOGNAME': 'cltbld', 21:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:59 INFO - 'MOZ_NO_REMOTE': '1', 21:34:59 INFO - 'NO_EM_RESTART': '1', 21:34:59 INFO - 'PAGER': '/bin/cat', 21:34:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:59 INFO - 'PWD': '/builds/slave/test', 21:34:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:59 INFO - 'SHELL': '/bin/bash', 21:34:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:34:59 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:34:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:59 INFO - 'USER': 'cltbld', 21:34:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:59 INFO - 'XPC_FLAGS': '0x0', 21:34:59 INFO - 'XPC_SERVICE_NAME': '0', 21:34:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 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)) 21:35:00 INFO - Cleaning up... 21:35:00 INFO - Return code: 0 21:35:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:00 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 0x10f72b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9771dc6b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, '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.rTCuW7nPZz/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.mHcOimJICp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 21:35:00 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 21:35:00 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 21:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:00 INFO - 'HOME': '/Users/cltbld', 21:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:00 INFO - 'LOGNAME': 'cltbld', 21:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:00 INFO - 'MOZ_NO_REMOTE': '1', 21:35:00 INFO - 'NO_EM_RESTART': '1', 21:35:00 INFO - 'PAGER': '/bin/cat', 21:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:00 INFO - 'PWD': '/builds/slave/test', 21:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:00 INFO - 'SHELL': '/bin/bash', 21:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:35:00 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:00 INFO - 'USER': 'cltbld', 21:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:00 INFO - 'XPC_FLAGS': '0x0', 21:35:00 INFO - 'XPC_SERVICE_NAME': '0', 21:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:35:00 INFO - Cleaning up... 21:35:00 INFO - Return code: 0 21:35:00 INFO - Running post-action listener: _resource_record_post_action 21:35:00 INFO - Running post-action listener: _start_resource_monitoring 21:35:00 INFO - Starting resource monitoring. 21:35:00 INFO - [mozharness: 2017-01-21 05:35:00.803133Z] Finished create-virtualenv step (success) 21:35:00 INFO - [mozharness: 2017-01-21 05:35:00.804078Z] Running install step. 21:35:00 INFO - Running pre-action listener: _resource_record_pre_action 21:35:00 INFO - Running main action method: install 21:35:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:35:01 INFO - Reading from file tmpfile_stdout 21:35:01 INFO - Detecting whether we're running mozinstall >=1.0... 21:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:35:01 INFO - Reading from file tmpfile_stdout 21:35:01 INFO - Output received: 21:35:01 INFO - Usage: mozinstall [options] installer 21:35:01 INFO - Options: 21:35:01 INFO - -h, --help show this help message and exit 21:35:01 INFO - -d DEST, --destination=DEST 21:35:01 INFO - Directory to install application into. [default: 21:35:01 INFO - "/builds/slave/test"] 21:35:01 INFO - --app=APP Application being installed. [default: firefox] 21:35:01 INFO - mkdir: /builds/slave/test/build/application 21:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 21:35:33 INFO - Reading from file tmpfile_stdout 21:35:33 INFO - Output received: 21:35:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:35:33 INFO - Running post-action listener: _resource_record_post_action 21:35:33 INFO - [mozharness: 2017-01-21 05:35:33.758701Z] Finished install step (success) 21:35:33 INFO - [mozharness: 2017-01-21 05:35:33.758851Z] Running run-tests step. 21:35:33 INFO - Running pre-action listener: _resource_record_pre_action 21:35:33 INFO - Running main action method: run_tests 21:35:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:35:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:35:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:35:33 INFO - 2017-01-20 21:35:33.774 screenresolution[12246:243904] starting screenresolution argv=screenresolution get 21:35:33 INFO - 2017-01-20 21:35:33.777 screenresolution[12246:243904] Display 0: 1600x1200x32@60 21:35:33 INFO - 2017-01-20 21:35:33.783 screenresolution[12247:243906] starting screenresolution argv=screenresolution list 21:35:33 INFO - Available Modes on Display 0 21:35:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:35:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:35:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:35:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:35:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:35:34 INFO - Intel Iris: 21:35:34 INFO - Chipset Model: Intel Iris 21:35:34 INFO - Type: GPU 21:35:34 INFO - Bus: Built-In 21:35:34 INFO - VRAM (Dynamic, Max): 1536 MB 21:35:34 INFO - Vendor: Intel (0x8086) 21:35:34 INFO - Device ID: 0x0a2e 21:35:34 INFO - Revision ID: 0x0009 21:35:34 INFO - Displays: 21:35:34 INFO - Display: 21:35:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:35:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:35:34 INFO - Display Serial Number: 1600x1200 60 21:35:34 INFO - Main Display: Yes 21:35:34 INFO - Mirror: Off 21:35:34 INFO - Online: Yes 21:35:34 INFO - Rotation: Supported 21:35:34 INFO - Return code: 0 21:35:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:35:34 INFO - Python 2.7.3 21:35:34 INFO - Return code: 0 21:35:34 INFO - grabbing minidump binary from tooltool 21:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:34 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 0x10f53fd30>, '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 0x10f3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f537b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:35:34 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 21:35:34 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 21:35:34 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 21:35:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:35:34 INFO - Return code: 0 21:35:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:35:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:35:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:35:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:35:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:35:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 21:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0064 --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 21:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rTCuW7nPZz/Render', 21:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:34 INFO - 'HOME': '/Users/cltbld', 21:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:34 INFO - 'LOGNAME': 'cltbld', 21:35:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:35:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:34 INFO - 'MOZ_NO_REMOTE': '1', 21:35:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:35:34 INFO - 'NO_EM_RESTART': '1', 21:35:34 INFO - 'PAGER': '/bin/cat', 21:35:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:34 INFO - 'PWD': '/builds/slave/test', 21:35:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:34 INFO - 'SHELL': '/bin/bash', 21:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mHcOimJICp/Listeners', 21:35:34 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 21:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:34 INFO - 'USER': 'cltbld', 21:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:34 INFO - 'XPC_FLAGS': '0x0', 21:35:34 INFO - 'XPC_SERVICE_NAME': '0', 21:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 21:35:34 INFO - mozversion application_buildid: 20170120193239 21:35:34 INFO - mozversion application_changeset: 3209927fdbf97ab394f4506d10546a5debce1015 21:35:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:35:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:35:34 INFO - mozversion application_name: Firefox 21:35:34 INFO - mozversion application_remotingname: firefox-dev 21:35:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:35:34 INFO - mozversion application_vendor: Mozilla 21:35:34 INFO - mozversion application_version: 52.0a2 21:35:34 INFO - mozversion platform_buildid: 20170120193239 21:35:34 INFO - mozversion platform_changeset: 3209927fdbf97ab394f4506d10546a5debce1015 21:35:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:35:34 INFO - mozversion platform_version: 52.0a2 21:35:34 INFO - using testdate: 1484976934 21:35:34 INFO - actual date: 1484976934 21:35:34 INFO - starting webserver on 'localhost:58092' 21:35:34 INFO - SUITE-START | Running 2 tests 21:35:34 INFO - TEST-START | dromaeo_css 21:35:34 INFO - operating with platform_type : mac_ 21:35:34 INFO - Initialising browser for dromaeo_css test... 21:35:34 INFO - TEST-INFO | started process 12257 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpLqJveC/profile http://localhost:58092/getInfo.html) 21:35:36 INFO - PROCESS | 12257 | __metrics Screen width/height:1600/1200 21:35:36 INFO - PROCESS | 12257 | colorDepth:24 21:35:36 INFO - PROCESS | 12257 | Browser inner width/height: 1024/689 21:35:36 INFO - PROCESS | 12257 | __metrics 21:35:37 INFO - PROCESS | 12257 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:35:37 INFO - PROCESS | 12257 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:35:37 INFO - TEST-INFO | 12257: exit 0 21:35:37 INFO - Browser initialized. 21:35:37 INFO - Running cycle 1/1 for dromaeo_css test... 21:35:37 INFO - TEST-INFO | started process 12259 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpLqJveC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:50 INFO - PROCESS | 12259 | RSS: Main: 222130176 21:35:50 INFO - PROCESS | 12259 | 21:38:54 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58092/tests/dromaeo/cssquery-ext.html) 21:38:55 INFO - PROCESS | 12259 | RSS: Main: 340201472 21:38:55 INFO - PROCESS | 12259 | 21:41:58 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-ext.html (next: http://localhost:58092/tests/dromaeo/cssquery-jquery.html) 21:41:59 INFO - PROCESS | 12259 | RSS: Main: 350748672 21:41:59 INFO - PROCESS | 12259 | 21:45:01 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58092/tests/dromaeo/cssquery-mootools.html) 21:45:02 INFO - PROCESS | 12259 | RSS: Main: 316051456 21:45:02 INFO - PROCESS | 12259 | 21:48:04 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58092/tests/dromaeo/cssquery-prototype.html) 21:48:05 INFO - PROCESS | 12259 | RSS: Main: 316542976 21:48:05 INFO - PROCESS | 12259 | 21:51:08 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58092/tests/dromaeo/cssquery-yui.html) 21:51:09 INFO - PROCESS | 12259 | RSS: Main: 307191808 21:51:09 INFO - PROCESS | 12259 | 21:54:12 INFO - PROCESS | 12259 | Cycle 1(1): loaded http://localhost:58092/tests/dromaeo/cssquery-yui.html (next: http://localhost:58092/tests/dromaeo/cssquery-dojo.html) 21:54:13 INFO - PROCESS | 12259 | RSS: Main: 361807872 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | __start_tp_report 21:54:13 INFO - PROCESS | 12259 | _x_x_mozilla_page_load 21:54:13 INFO - PROCESS | 12259 | _x_x_mozilla_page_load_details 21:54:13 INFO - PROCESS | 12259 | |i|pagename|runs| 21:54:13 INFO - PROCESS | 12259 | |0;dojo.html;2949.690282520335;3025.818450892948;3222.2588804574934;3268.297316077043;3326.18508465426;5650.406707295737;5708.828735137952;5726.3700992890745;5738.225339579156;5802.303723553175;5699.458551437607;5723.313202415716;5774.133879918015;5775.653460792355;5817.650940943547;4254.9149017019645;5203.427622961472;5245.291885595453;5249.86875328116;5281.841544753664;5182.922256166161;5187.584993200554;5188.584913206934;5334.333208348957;5377.247185394048;4358.673099517533;5285.867853303679;5300.893982120355;5307.469253074681;5311.867203319928;5122.3853137623355;5131.7434128293435;5171.2501687255135;5204.4535323791215;5223.425423203483;4336.262835318003;5171.327727395452;5180;5197.116490196638;5236.136037553799;5768.077107662753;5773.971130144322;5855.677937713424;5856.76572937082;5863.677497737623;5112.718800465973;6114.663693496856;6158.168647232611;6161.4146656067605;6230.844228894269;6133.089845874415;6250.4374606285655;6260.655663938482;6261.342559031327;6275.592086514362;4912.926306105412;5903.970480147572;5905.409459054146;5919.112133180014;5919.852003699856;6167.84580385494;6205.906911396242;6235.345288744707;6266.968665156736;6296.779612713624;6209.9379006210265;6216.067589861557;6217.533684973645;6220.471259942865;6224;6295.244570651551;6305.8108256752375;6312.873742525124;6319.368063193644;6323.841903952346;6115.788007244199;6152.753889844356;6192.752289908443;6231.501479881598;6251.187345645037;6272.996320588684;6277.403646653561;6300.653464059429;6302.653354065479;6313.589616674902;4919.606431485473;4950.3069570260195;4953.578945789576;4956.4547899731;4963.528464795839;4368.694191406571;4411.338299255073;4412.227860124465;4432.800523976429;4483.977580112144;5708.800191993343;5741.799037033684;5762.88474230513;5765.106408506688;5770.365259821417;6190.81427557174;6245.6252624841745;6254.749810007549;6279.026750853625;6289.308176100625;4877.634177436636;5842.678652674059;5847.795327163529;5853.1220316952795;5856.707164641751;4860.1737704590305;5358.8660283492445;5384.165454354581;5432.945670543244;5490.890182196334;6140.718981675666;6206.317305096526;6264.185655864709;6277.654728989949;6281.937180628135;6196.070589411444;6240.063990401476;6261.530385221217;6265.342139075331;6280.528960328085;6114.755409783559;6208.906866397098;6277.905831412624;6280.183576135073;6373.681315934092;6089.1779609753885;6127.80043271483;6238;6245.656488893154;6255.374462553708;6148;6194.38056194377;6254.843628909313;6292.150559674568;6335.334789847;6056.606320589148;6076.240469941257;6189.690515474298;6202.937970620416;6227.626342419469;5957.4042595740075;6069.969650151721;6191.226096737908;6215.502759779297;6224.595401298901;5902.793402230901;5979.730912109053;5994.340622531605;6000.159977603226;6091.238595175603;6132.733139465431;6171.413715697002;6180.6600636965395;6187.84530386744;6190.969045154746;6030.909536357046;6034.900509069829;6059.212302400836;6060.1212824139375;6086.756529738938 21:54:13 INFO - PROCESS | 12259 | |1;ext.html;457.5264601137823;471.67690132259406;503.3205173016426;508.278244892253;517.9663321884079;29356.26609334765;33744.156396090046;38813.417798733004;39989.00027499314;40156;41283.9679008025;41511.16977660445;41579.16841663165;41659.95850103749;41719;9257.074292570756;9436.480993545352;9436.528173591292;9443.244540436748;9450.196733277646;8323.750287491355;8568.742937711879;9257.6296948122;11309.547618095266;11346;11141.610043648474;11197.27217730849;11223.831642525369;11224.831627525593;11231.26996745213;8326.292265157468;11196.608118715847;11268.549258029669;11275.267107637977;11277.32336059839;8303.086660467343;10885.020348168624;11171.050460710849;11252.718682033015;11412.258203216765;38837;39251.60748392508;39298;39315.41026884599;39318.803405983075;3205.935881282361;3215.180129067073;3265.4775235962134;3308.139883630249;3344.1973926257765;24185.395365115837;28299.71700283012;28941.565876511868;29229.561556576667;30064.947726829458;2577.3814284571754;3146.4179126861604;3159.4628913084603;3161.8419079045957;3285.0801775502896;495189;495702;496279.5186024083;497223;497867;50000.49999499958;57822;58141.70929145412;58154.41845581574;58245;60709.392906071254;60781.69609152014;60800;60854.69572652197;60927.08609370863;15676;15904.363825447084;15911.204439777965;15916.602084947737;15917.044977301399;11125.221234513507;11317.49071291794;12297.323647199311;12377.6286711397;12395;15527.223638818014;15987.520374388787;15989.200539972955;16183.757243641356;16381.918090409472;6227.501799856001;6255.249370075529;6272.18461599989;6343.777967771106;6392.232932048091;847.3729440214197;966.4346357380871;980.843065109579;985.265976847239;1054.3252318516156;28388;28519.57220641692;29057.273568160967;29189;29284.85357573241;1030.8144533983955;1036.4092467293722;1074.4735079810994;1075.704181350135;1075.8063548561177;12366.567170149001;14489.623485768836;14883.553493395215;15086.281812538797;15315.846841531666;599397;604773;604821;849780;851931;46462.53537464582;54170.45829541733;56266;56315.59211019758;59078.70460647669;1316.7498175346846;1326.4561529772748;1334.046156997732;1353.012301020241;1361.748076605831;29148.854255728587;29887.252818679706;29925.55111673283;30192.201705039603;30678;48619.513804862916;48894.26658600195;48993;49091.509084910125;49126.01747965092;485781;488837;488908;489180;490201;37842.810785947;38379.808100960436;38510.42234366431;38518.614813851505;38523;44813.551864480934;45285.54714452814;45843;45846.08307833891;46043.76978115088;35971.99033118173;36486.63513364832;37381.62618373781;38458.80770596129;38644.420333695576;37383.06542336354;37590;38553.80723096479;38835.611643883196;38875;36283.818580906925;36946.815265924575;37288.44067338938;37442.812785935894;37595.624043760305;31635.36729265356;33377.33245335031;33989;34024.65975340215;34229.48655770116;21376;24978.250652480456;24990.75009249957;25390.619140713272;25412.74587254178 21:54:13 INFO - PROCESS | 12259 | |2;jquery.html;341.701011614837;342.37516532328505;371.263042859923;379.491481414904;397.634176557567;14396.712065758678;14462.421503139874;15497.457588984387;19670.40988770339;20138.19447222113;18810.811891881076;18814.905925470386;19910.203591856345;20083.799162008374;20089.59820803583;5264.657797243177;5549.0289199390145;5551.500364967153;5562.694051827148;5564.944350556503;4500.8874778130485;5457.863553411176;5475.123980163175;5501.312335958005;5510.531604813596;5492.670439773607;5495.2856128703215;5497.45025497451;5498.80754173604;5518.503334699896;5462.289902312694;5837.532997360201;5945.8810823783715;5966.940330596682;6008.399160083956;4686.437627484724;5138.561202863204;5329.067413202674;5336.146216605324;5337.146056630921;12832;13474.865251347459;13485.730285394236;13501.59495215145;13504.932475337659;2157.439065842892;2167.9024443899903;2174.3368272676826;2865.2550336912554;2934.193096898349;9771;9872.16086632637;10225.64210252645;10241.07830295277;10535.63125290611;1730.7663465432258;1808.3489943620286;1846.1599972012702;1858.4703359542466;1880.7931127575955;113013;113718.43140784244;124217.75782242152;125661;127982.44035119339;10738.624148154777;11089.83365249522;11371.39458519301;11391.249245323594;11562;14436.206008669415;14460;14463;14468.348924298221;14478.85521144796;8080.111187769341;8341.541215233221;8381;8487.872681909776;8520.105388934197;8158.449894520022;8161.79595510117;8193.221643943816;8308;8339.416240863082;3850.8844734658005;4414.514403415622;4516.503184649686;4527.909441811211;4576.359309696645;1563.2965165675491;1858.1545396844404;1876.3807943378652;1896.7249748786348;1903.1911437638948;756.1266736918867;854.5385491834464;887.7394100377479;930.2697382554662;937.5078084005816;16403.83596164047;18758.906205468884;20936.581268374546;21012.684809727867;21070.841103739134;720.909886264217;883.1654086887829;926.9860952085725;940.3323640215386;949.0746522140857;10338.379697218192;11061.391623460788;11065.50205240766;11072.39101849406;11093.223474356879;504641;507532;509840;586325;612835;78801;131003.17476189324;133268.002939866;135605;141620.5837941628;928.8699582058517;962.8507581324906;974.8099120671401;978.4178413843752;985.0199051943363;15360.9231953844;17676.11619418998;18315.72526412078;18969.24123035063;19300.903495482995;35945.820270898475;48181.03637927292;51433.48566514287;53072.938541229734;56647.43352566587;66057.33942660512;73362.63318683373;78010.60994695217;79461.60269198849;80204;7702.691892324245;7760.581819629734;7899.486533375312;8045.195480452142;8287.419880608299;11599.478023488795;11887.405629718307;12252.860700828462;12503.624891253277;12618.936905315415;10571.735706607396;10685.679429617125;10873.293235939638;10914.454277286262;10931.83602245951;4910.6562540623245;7873.858290547953;10604.575816967235;10694.572217111228;11008.954149355799;10729.839052414063;11076.833847492133;11182.217244793112;11191.2725672831;11224.071574948703;7351.081114860643;7809.297163255334;7818.687252510063;8056.597170141352;8155.062167850773;6215.285242197205;9204.253758709025;10554;10893.078491742808;11072.557097716324 21:54:13 INFO - PROCESS | 12259 | |3;mootools.html;1415.1650526189549;1461.0576178364954;1578.2976575423934;1749.8862573932686;1763.964720705586;7491.812704682372;8065.193480651939;8490.6179221935;8514.91485085149;8530.872036919443;7570.5079169853925;7718.845623087549;8084.070331911832;8318.70884519042;8321.500709957407;1952.5021119614478;2291.117919600953;2326.5114325991563;2327.2320134355705;2332.3352844439337;2064.194963964055;2304.0207911637485;2329.534093181362;2353.388119089032;2358.1864256831363;1769.867259955502;2091.6444204485197;2123.883186424746;2123.9150433982622;2125.51113243954;2237.3288013595857;2302.2747834432184;2326.1044497868274;2330.1844354475966;2330.463993281555;2329.9650505242435;2339.239747082188;2344.8358614896965;2349.7415284318713;2366.514864452783;8403.201695838885;8419.621117049748;8506.74479765608;8515.48907065578;8522.70170544028;4242.512111107231;4900.926486102711;5047.949520504784;5066.974665126688;5070.214116811863;3159.3207460396125;3381.6111147217894;3411.9147021324416;3510.3154884797473;3581.928361432783;1011.585250047477;1049.0558497352367;1079.2607064161075;1091.1707102602;1092.6230450494568;10929.56281748729;10974.945125274402;10983.450827458675;10987.725306867313;11045;4165.416841642173;4438.556144385536;4600.401947746838;4689.320048592935;4697.976510117427;9431.717048488556;9432.95283523578;9441.952790236004;9458.668946586975;9461.90538094624;5201.245819356172;5213.635045546851;5216.66091704038;5219.973900130475;5230.686158830483;4517.5256598057385;4736.715797052188;4789.473157952622;4798.184308667535;4800.183968725325;3755.3052685253315;3771.6228377162065;3815.6565909067763;3856.305864944337;3879.1078052048188;1216.0940099625836;1239.3741160713782;1244.2783185752423;1252.1297698099916;1255.6205989649563;750.7785203365021;851.9446235994641;882.5543100734088;884.0187391994881;892.5492626223845;4765.523447655207;5595.4124816894455;5655.208270842086;5813.651180929158;6013.428724271187;872.6727477196051;892.5715656484793;930.2325581395376;954.2747511891013;957.0716405087054;3734.2158146788934;3848.422736589478;3859.710521710827;3949.8025098744947;4012.2376748417705;9594.712158635251;10116.949415252877;10142.949285253526;10180.796384072277;10190;9452.291078169164;9953.303268771117;9967.950160249153;10019;10085.243606729378;7617.466777325645;7816.257455541714;7836.098848632366;7843.9215607844335;7852.646630901623;5702.40124786908;5715.5139663686905;5728.140778883201;5755.331356273286;6045.697715114315;9340.673076442292;9417.387869788441;9418.434893906388;9431.292653050912;9462.858057129011;7223.530470519399;9450.102240287162;9461.148496635336;9565.139137477661;9588.520573971413;7113.430925526049;7277.890831637424;7278.526895751759;7284.890726639211;7285.23505031964;7708.768736937676;7711;7742.109657389472;7876.369890408868;7917.247861453153;7197.028401165776;7199.892001620085;7215.242399547972;7224.855502889807;7268.89096663561;5797.333306669787;6738.124043874265;6908.930910690829;7002.2297547268745;7014.894776578253;6654.800355989328;6689.76585819494;6746.190457145174;6749.730010799513;6774.42417394527;4743.146233677971;4780.498047704941;4886.267059941038;4991.475895031114;5088.465711100281;4831.782569784438;4844.563989240985;4949.529794669501;4978.253262010728;5047 21:54:13 INFO - PROCESS | 12259 | |4;prototype.html;297.4244836241872;301.33254840528224;308.3693846084756;312.28642551769195;313.3372916282064;4652.511486293941;4863.562279394854;5306.575473962083;5344.759485823138;5357.866053348664;4162.687798415116;4599.8390056348035;5121.743912804354;5149.948500515003;5295.708736019517;4002.5797291284434;4023.9798801006027;4026.879193624196;4029.899252518696;4079.530853951789;3601.405768048269;3886.7667939923554;3905.7892053463374;3998.1204135090234;4055.5944405559503;3681.5582130144285;3817.3701339279123;3828.808559572024;3891.571927088018;4006.73956192848;3316.6849149330774;3843.8846834595006;3970.2456533258587;4011.2579172853116;4027.637512623878;3199.2641692410643;3518.437050072002;3525.012996361023;3525.3301872644115;3528.206153615416;4554.929591546017;5163.115462856031;5163.870903227412;5210.609204309692;5228.320318358627;3745.382011968022;4526.95473045272;4577.702449340783;4602;4649.18639238132;5545.584081193927;5600.719964001824;5618.91571626426;5637.379888212294;5659.189688764517;3281.573395458599;3703.4444833275224;3813.656770890633;3826.349520581508;3846.7114966377635;5123.513266239738;5636.661800291996;5761.510271626917;5778.515238447508;5793.652380857162;3221.64561898191;3761.273077019194;3829.310724069694;3849.40334248192;3856.2480316338606;3895.104126051024;3895.9935633148953;3916.6279203475624;3922.941155882664;3950.3481925482547;3568.411212150018;3779.6787273082095;3923.6664883485223;4210.6841986851105;4583.197940360424;4681.852946028245;5548.144039892236;5556.6943818089585;5564.554835613141;5587.972060139673;1215.343714394235;1262.3688155922039;1263.3051821498138;1272.1985149355846;1314.769915264825;757.715671936074;804.9597520124088;819.4345901328064;820.5281962871253;825.3562221467265;4326.502452217972;4489.371487991619;4645.790939407735;4900.14247506692;5397.865053373697;4896.387951506062;5268.4994925482015;5687.544996400278;5723.399043100498;5796.304443466726;4473.910521789546;5244.29202057725;5267.787529237653;5345.35855697311;5351.652142610796;2477.380654836291;2599.506093842164;2810.4660114578164;2821.5908693239367;2845.2460098074025;4573.771311434415;4698.812047518061;5459.697137194605;5465.988792073479;5554.833354999357;5295.76169072385;5562.499375056264;5574.58190635704;5590.357108932525;5657.688827114466;4707.646926480459;4760.786703767191;4798.832040878622;5196.596918831894;5558.777648893999;4232.095556999301;5419.403865574863;5467.152591348347;5517.641353312022;5556.194351819045;5343.4389389113585;5348.518633322864;5355.651882627617;5363.0346537622045;5369.1878766076525;4519.977400112978;5470.510917532592;5528.037546671035;5557.527610153183;5575.776968921197;3422.9046705054143;3514.7715398499017;3568.9663785781877;3569.1790888095366;3610.927781444409;4656.627469802475;4884.4382895966;4891.095147397743;5106.514881086291;5333.306670132858;4293.914121717486;5176.689398636094;5213.165893457029;5255.97917593034;5311.122880297482;4605.894585299571;4632.86101416958;4877.853664390074;5145.967342899608;5277.540478295988;4575.954240457686;4658.277966915133;4903.926441103315;4964.925526117039;5327.121025030827;4917.483664215206;5217.956408718195;5388.838334849961;5513.531349835149;5547.639403438763;4585.633149347977;5175.844724658266;5384.326959130109;5399.325084364455;5414.994677953773 21:54:13 INFO - PROCESS | 12259 | |5;yui.html;751.6955632968646;754.34748942165;765.888946102815;774.4965772248041;794.7139029949217;15549.922250388747;16170.595735106628;16213.270402831871;16215.27031283592;16284;14820.333085011174;14912.850871491282;14942.252887355644;14953.401863925457;14961.850381496182;631.3528633151011;651.4983462733692;804.0953926832314;828.8425199212161;831.600831600832;652.3769799841164;787.94484386093;813.0649752784286;814.2061490047216;818.1899919080098;866.527742380401;870.0951010948606;870.9695160669378;871.3464901324007;872.9960545372809;866.2117473099449;867.4318321499428;867.4708427859001;868.097178933908;869.8738682890977;493.83950216179886;502.89538282288373;614.1371373220633;632.1213513216633;637.1366797988705;13983.510577129853;14011.229382383965;14066.085704429179;14095.295235238196;14128.081674691215;12515.749685006249;12580.182288151333;12727.554535591209;12806.231626102464;12897;12045.518179272818;12073.637790866289;12095.939520302341;12113;12223.75552488954;4147.3778933159965;4161.8959526012095;4240.830366785325;4389.231884420214;4397.362382454558;78226.6088669553;83542.58228708878;88253.11746882513;88268;88383;9886.555105020292;9949.154321882575;10095.646652367132;10118.797624047478;10166.796664066826;18731.812681873278;18823.623527529646;18831.90584047071;18879.339223127125;19026.334078307194;7215.134183897861;7239.8552028960175;7300.233475485104;7310.597917114504;7435.8141046474275;4033.2675305141975;4056.452378928866;4229.344451610005;4417.624501917373;4438.400815889878;3349.363620912019;3412.8805491807666;3447.844846981892;3453.8445769940413;3475.391806433864;565.4486875296553;650.8145178624069;653.0726368556661;659.3373659472296;662.9734810607619;528.5190476666215;543.8531596468931;561.2351138831293;567.3843879390824;649.4544582550681;7217.3533727248;8261.710840120568;8448.281896038905;8938.195562399414;9048.909510904807;584.4944123333286;615.1971676961518;648.1412128929188;663.0750103605443;681.5297444763098;4160.771157586361;4371.672124590668;4378.408914796461;4428.512863585003;4456.977715111404;77674.22325776782;99290.0141997156;104729;107130;107766.46116769366;89451.21097578012;91014.54492727494;92086.53956730307;95111.0488895116;95350;52586.737066314425;67160;75439.58763505054;75956.62021689856;77954;6837.01383657764;7305;8462.238398544161;8621.956890215759;8846.646134154562;51747;54172.72913635564;54262.45737542574;54803.17795232995;54920.72539637276;71289.28710712827;74138.88791668238;75989.62005189939;76475;77194;11603.941980290383;12011.819822702491;12044;12258.754824903273;12437.813432798333;13054.738905221653;14098.872090232962;14127.788083178555;14684.706305874037;14902.552923412315;12195.268283902995;13168.868311316764;13172.143810652276;13446.663833404244;13814.792778108136;13307.600771976857;13340.533081342106;13525.256110913993;13809.51666691661;13870.167789932637;12574.685632859251;12888.291143987171;13051;13200.339983001004;13568.457261709422;10316.43259620728;10572.259941804308;10729.302595331279;11019.393933333744;11101.50043248072;10438.321509101885;10836.499054027847;11157.386343751172;11484.253523520945;11484.425778711198 21:54:13 INFO - PROCESS | 12259 | __end_tp_report 21:54:13 INFO - PROCESS | 12259 | __start_cc_report 21:54:13 INFO - PROCESS | 12259 | _x_x_mozilla_cycle_collect,1392 21:54:13 INFO - PROCESS | 12259 | __end_cc_report 21:54:13 INFO - PROCESS | 12259 | __startTimestamp1484978053025__endTimestamp 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | ------- Summary: start ------- 21:54:13 INFO - PROCESS | 12259 | Number of tests: 6 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#0] dojo.html Cycles:165 Average:5734.26 Median:6034.90 stddev:698.03 (11.6%) stddev-sans-first:665.12 21:54:13 INFO - PROCESS | 12259 | Values: 2949.7 3025.8 3222.3 3268.3 3326.2 5650.4 5708.8 5726.4 5738.2 5802.3 5699.5 5723.3 5774.1 5775.7 5817.7 4254.9 5203.4 5245.3 5249.9 5281.8 5182.9 5187.6 5188.6 5334.3 5377.2 4358.7 5285.9 5300.9 5307.5 5311.9 5122.4 5131.7 5171.3 5204.5 5223.4 4336.3 5171.3 5180.0 5197.1 5236.1 5768.1 5774.0 5855.7 5856.8 5863.7 5112.7 6114.7 6158.2 6161.4 6230.8 6133.1 6250.4 6260.7 6261.3 6275.6 4912.9 5904.0 5905.4 5919.1 5919.9 6167.8 6205.9 6235.3 6267.0 6296.8 6209.9 6216.1 6217.5 6220.5 6224.0 6295.2 6305.8 6312.9 6319.4 6323.8 6115.8 6152.8 6192.8 6231.5 6251.2 6273.0 6277.4 6300.7 6302.7 6313.6 4919.6 4950.3 4953.6 4956.5 4963.5 4368.7 4411.3 4412.2 4432.8 4484.0 5708.8 5741.8 5762.9 5765.1 5770.4 6190.8 6245.6 6254.7 6279.0 6289.3 4877.6 5842.7 5847.8 5853.1 5856.7 4860.2 5358.9 5384.2 5432.9 5490.9 6140.7 6206.3 6264.2 6277.7 6281.9 6196.1 6240.1 6261.5 6265.3 6280.5 6114.8 6208.9 6277.9 6280.2 6373.7 6089.2 6127.8 6238.0 6245.7 6255.4 6148.0 6194.4 6254.8 6292.2 6335.3 6056.6 6076.2 6189.7 6202.9 6227.6 5957.4 6070.0 6191.2 6215.5 6224.6 5902.8 5979.7 5994.3 6000.2 6091.2 6132.7 6171.4 6180.7 6187.8 6191.0 6030.9 6034.9 6059.2 6060.1 6086.8 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#1] ext.html Cycles:180 Average:69247.79 Median:37516.41 stddev:154149.70 (410.9%) stddev-sans-first:154495.60 21:54:13 INFO - PROCESS | 12259 | Values: 457.5 471.7 503.3 508.3 518.0 29356.3 33744.2 38813.4 39989.0 40156.0 41284.0 41511.2 41579.2 41660.0 41719.0 9257.1 9436.5 9436.5 9443.2 9450.2 8323.8 8568.7 9257.6 11309.5 11346.0 11141.6 11197.3 11223.8 11224.8 11231.3 8326.3 11196.6 11268.5 11275.3 11277.3 8303.1 10885.0 11171.1 11252.7 11412.3 38837.0 39251.6 39298.0 39315.4 39318.8 3205.9 3215.2 3265.5 3308.1 3344.2 24185.4 28299.7 28941.6 29229.6 30064.9 2577.4 3146.4 3159.5 3161.8 3285.1 495189.0 495702.0 496279.5 497223.0 497867.0 50000.5 57822.0 58141.7 58154.4 58245.0 60709.4 60781.7 60800.0 60854.7 60927.1 15676.0 15904.4 15911.2 15916.6 15917.0 11125.2 11317.5 12297.3 12377.6 12395.0 15527.2 15987.5 15989.2 16183.8 16381.9 6227.5 6255.2 6272.2 6343.8 6392.2 847.4 966.4 980.8 985.3 1054.3 28388.0 28519.6 29057.3 29189.0 29284.9 1030.8 1036.4 1074.5 1075.7 1075.8 12366.6 14489.6 14883.6 15086.3 15315.8 599397.0 604773.0 604821.0 849780.0 851931.0 46462.5 54170.5 56266.0 56315.6 59078.7 1316.7 1326.5 1334.0 1353.0 1361.7 29148.9 29887.3 29925.6 30192.2 30678.0 48619.5 48894.3 48993.0 49091.5 49126.0 485781.0 488837.0 488908.0 489180.0 490201.0 37842.8 38379.8 38510.4 38518.6 38523.0 44813.6 45285.5 45843.0 45846.1 46043.8 35972.0 36486.6 37381.6 38458.8 38644.4 37383.1 37590.0 38553.8 38835.6 38875.0 36283.8 36946.8 37288.4 37442.8 37595.6 31635.4 33377.3 33989.0 34024.7 34229.5 21376.0 24978.3 24990.8 25390.6 25412.7 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#2] jquery.html Cycles:180 Average:32600.75 Median:18158.54 stddev:91835.81 (505.7%) stddev-sans-first:92061.49 21:54:13 INFO - PROCESS | 12259 | Values: 341.7 342.4 371.3 379.5 397.6 14396.7 14462.4 15497.5 19670.4 20138.2 18810.8 18814.9 19910.2 20083.8 20089.6 5264.7 5549.0 5551.5 5562.7 5564.9 4500.9 5457.9 5475.1 5501.3 5510.5 5492.7 5495.3 5497.5 5498.8 5518.5 5462.3 5837.5 5945.9 5966.9 6008.4 4686.4 5138.6 5329.1 5336.1 5337.1 12832.0 13474.9 13485.7 13501.6 13504.9 2157.4 2167.9 2174.3 2865.3 2934.2 9771.0 9872.2 10225.6 10241.1 10535.6 1730.8 1808.3 1846.2 1858.5 1880.8 113013.0 113718.4 124217.8 125661.0 127982.4 10738.6 11089.8 11371.4 11391.2 11562.0 14436.2 14460.0 14463.0 14468.3 14478.9 8080.1 8341.5 8381.0 8487.9 8520.1 8158.4 8161.8 8193.2 8308.0 8339.4 3850.9 4414.5 4516.5 4527.9 4576.4 1563.3 1858.2 1876.4 1896.7 1903.2 756.1 854.5 887.7 930.3 937.5 16403.8 18758.9 20936.6 21012.7 21070.8 720.9 883.2 927.0 940.3 949.1 10338.4 11061.4 11065.5 11072.4 11093.2 504641.0 507532.0 509840.0 586325.0 612835.0 78801.0 131003.2 133268.0 135605.0 141620.6 928.9 962.9 974.8 978.4 985.0 15360.9 17676.1 18315.7 18969.2 19300.9 35945.8 48181.0 51433.5 53072.9 56647.4 66057.3 73362.6 78010.6 79461.6 80204.0 7702.7 7760.6 7899.5 8045.2 8287.4 11599.5 11887.4 12252.9 12503.6 12618.9 10571.7 10685.7 10873.3 10914.5 10931.8 4910.7 7873.9 10604.6 10694.6 11009.0 10729.8 11076.8 11182.2 11191.3 11224.1 7351.1 7809.3 7818.7 8056.6 8155.1 6215.3 9204.3 10554.0 10893.1 11072.6 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#3] mootools.html Cycles:180 Average:5431.28 Median:5079.34 stddev:2983.19 (58.7%) stddev-sans-first:2976.29 21:54:13 INFO - PROCESS | 12259 | Values: 1415.2 1461.1 1578.3 1749.9 1764.0 7491.8 8065.2 8490.6 8514.9 8530.9 7570.5 7718.8 8084.1 8318.7 8321.5 1952.5 2291.1 2326.5 2327.2 2332.3 2064.2 2304.0 2329.5 2353.4 2358.2 1769.9 2091.6 2123.9 2123.9 2125.5 2237.3 2302.3 2326.1 2330.2 2330.5 2330.0 2339.2 2344.8 2349.7 2366.5 8403.2 8419.6 8506.7 8515.5 8522.7 4242.5 4900.9 5047.9 5067.0 5070.2 3159.3 3381.6 3411.9 3510.3 3581.9 1011.6 1049.1 1079.3 1091.2 1092.6 10929.6 10974.9 10983.5 10987.7 11045.0 4165.4 4438.6 4600.4 4689.3 4698.0 9431.7 9433.0 9442.0 9458.7 9461.9 5201.2 5213.6 5216.7 5220.0 5230.7 4517.5 4736.7 4789.5 4798.2 4800.2 3755.3 3771.6 3815.7 3856.3 3879.1 1216.1 1239.4 1244.3 1252.1 1255.6 750.8 851.9 882.6 884.0 892.5 4765.5 5595.4 5655.2 5813.7 6013.4 872.7 892.6 930.2 954.3 957.1 3734.2 3848.4 3859.7 3949.8 4012.2 9594.7 10116.9 10142.9 10180.8 10190.0 9452.3 9953.3 9968.0 10019.0 10085.2 7617.5 7816.3 7836.1 7843.9 7852.6 5702.4 5715.5 5728.1 5755.3 6045.7 9340.7 9417.4 9418.4 9431.3 9462.9 7223.5 9450.1 9461.1 9565.1 9588.5 7113.4 7277.9 7278.5 7284.9 7285.2 7708.8 7711.0 7742.1 7876.4 7917.2 7197.0 7199.9 7215.2 7224.9 7268.9 5797.3 6738.1 6908.9 7002.2 7014.9 6654.8 6689.8 6746.2 6749.7 6774.4 4743.1 4780.5 4886.3 4991.5 5088.5 4831.8 4844.6 4949.5 4978.3 5047.0 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#4] prototype.html Cycles:180 Average:4344.31 Median:4734.22 stddev:1328.54 (28.1%) stddev-sans-first:1297.08 21:54:13 INFO - PROCESS | 12259 | Values: 297.4 301.3 308.4 312.3 313.3 4652.5 4863.6 5306.6 5344.8 5357.9 4162.7 4599.8 5121.7 5149.9 5295.7 4002.6 4024.0 4026.9 4029.9 4079.5 3601.4 3886.8 3905.8 3998.1 4055.6 3681.6 3817.4 3828.8 3891.6 4006.7 3316.7 3843.9 3970.2 4011.3 4027.6 3199.3 3518.4 3525.0 3525.3 3528.2 4554.9 5163.1 5163.9 5210.6 5228.3 3745.4 4527.0 4577.7 4602.0 4649.2 5545.6 5600.7 5618.9 5637.4 5659.2 3281.6 3703.4 3813.7 3826.3 3846.7 5123.5 5636.7 5761.5 5778.5 5793.7 3221.6 3761.3 3829.3 3849.4 3856.2 3895.1 3896.0 3916.6 3922.9 3950.3 3568.4 3779.7 3923.7 4210.7 4583.2 4681.9 5548.1 5556.7 5564.6 5588.0 1215.3 1262.4 1263.3 1272.2 1314.8 757.7 805.0 819.4 820.5 825.4 4326.5 4489.4 4645.8 4900.1 5397.9 4896.4 5268.5 5687.5 5723.4 5796.3 4473.9 5244.3 5267.8 5345.4 5351.7 2477.4 2599.5 2810.5 2821.6 2845.2 4573.8 4698.8 5459.7 5466.0 5554.8 5295.8 5562.5 5574.6 5590.4 5657.7 4707.6 4760.8 4798.8 5196.6 5558.8 4232.1 5419.4 5467.2 5517.6 5556.2 5343.4 5348.5 5355.7 5363.0 5369.2 4520.0 5470.5 5528.0 5557.5 5575.8 3422.9 3514.8 3569.0 3569.2 3610.9 4656.6 4884.4 4891.1 5106.5 5333.3 4293.9 5176.7 5213.2 5256.0 5311.1 4605.9 4632.9 4877.9 5146.0 5277.5 4576.0 4658.3 4903.9 4964.9 5327.1 4917.5 5218.0 5388.8 5513.5 5547.6 4585.6 5175.8 5384.3 5399.3 5415.0 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - PROCESS | 12259 | [#5] yui.html Cycles:180 Average:19698.76 Median:2475.41 stddev:28079.77 (1134.3%) stddev-sans-first:28122.50 21:54:13 INFO - PROCESS | 12259 | Values: 751.7 754.3 765.9 774.5 794.7 15549.9 16170.6 16213.3 16215.3 16284.0 14820.3 14912.9 14942.3 14953.4 14961.9 631.4 651.5 804.1 828.8 831.6 652.4 787.9 813.1 814.2 818.2 866.5 870.1 871.0 871.3 873.0 866.2 867.4 867.5 868.1 869.9 493.8 502.9 614.1 632.1 637.1 13983.5 14011.2 14066.1 14095.3 14128.1 12515.7 12580.2 12727.6 12806.2 12897.0 12045.5 12073.6 12095.9 12113.0 12223.8 4147.4 4161.9 4240.8 4389.2 4397.4 78226.6 83542.6 88253.1 88268.0 88383.0 9886.6 9949.2 10095.6 10118.8 10166.8 18731.8 18823.6 18831.9 18879.3 19026.3 7215.1 7239.9 7300.2 7310.6 7435.8 4033.3 4056.5 4229.3 4417.6 4438.4 3349.4 3412.9 3447.8 3453.8 3475.4 565.4 650.8 653.1 659.3 663.0 528.5 543.9 561.2 567.4 649.5 7217.4 8261.7 8448.3 8938.2 9048.9 584.5 615.2 648.1 663.1 681.5 4160.8 4371.7 4378.4 4428.5 4457.0 77674.2 99290.0 104729.0 107130.0 107766.5 89451.2 91014.5 92086.5 95111.0 95350.0 52586.7 67160.0 75439.6 75956.6 77954.0 6837.0 7305.0 8462.2 8622.0 8846.6 51747.0 54172.7 54262.5 54803.2 54920.7 71289.3 74138.9 75989.6 76475.0 77194.0 11603.9 12011.8 12044.0 12258.8 12437.8 13054.7 14098.9 14127.8 14684.7 14902.6 12195.3 13168.9 13172.1 13446.7 13814.8 13307.6 13340.5 13525.3 13809.5 13870.2 12574.7 12888.3 13051.0 13200.3 13568.5 10316.4 10572.3 10729.3 11019.4 11101.5 10438.3 10836.5 11157.4 11484.3 11484.4 21:54:13 INFO - PROCESS | 12259 | -------- Summary: end -------- 21:54:13 INFO - PROCESS | 12259 | 21:54:13 INFO - TEST-INFO | 12259: exit 0 21:54:13 INFO - TEST-OK | dromaeo_css | took 1119370ms 21:54:13 INFO - TEST-START | kraken 21:54:13 INFO - operating with platform_type : mac_ 21:54:13 INFO - Initialising browser for kraken test... 21:54:13 INFO - TEST-INFO | started process 12261 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp9i4mpg/profile http://localhost:58092/getInfo.html) 21:54:16 INFO - PROCESS | 12261 | __metrics Screen width/height:1600/1200 21:54:16 INFO - PROCESS | 12261 | colorDepth:24 21:54:16 INFO - PROCESS | 12261 | Browser inner width/height: 1024/689 21:54:16 INFO - PROCESS | 12261 | __metrics 21:54:16 INFO - PROCESS | 12261 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:54:16 INFO - PROCESS | 12261 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:54:17 INFO - TEST-INFO | 12261: exit 0 21:54:17 INFO - Browser initialized. 21:54:17 INFO - Running cycle 1/1 for kraken test... 21:54:17 INFO - TEST-INFO | started process 12262 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp9i4mpg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:54:29 INFO - PROCESS | 12262 | RSS: Main: 222195712 21:54:29 INFO - PROCESS | 12262 | 21:54:32 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58092/tests/kraken/driver.html?testName=audio-beat-detection) 21:54:33 INFO - PROCESS | 12262 | RSS: Main: 270344192 21:54:33 INFO - PROCESS | 12262 | 21:54:35 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58092/tests/kraken/driver.html?testName=audio-dft) 21:54:36 INFO - PROCESS | 12262 | RSS: Main: 285208576 21:54:36 INFO - PROCESS | 12262 | 21:54:39 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58092/tests/kraken/driver.html?testName=audio-fft) 21:54:40 INFO - PROCESS | 12262 | RSS: Main: 380985344 21:54:40 INFO - PROCESS | 12262 | 21:54:42 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58092/tests/kraken/driver.html?testName=audio-oscillator) 21:54:42 INFO - PROCESS | 12262 | RSS: Main: 283648000 21:54:42 INFO - PROCESS | 12262 | 21:54:44 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58092/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:54:45 INFO - PROCESS | 12262 | RSS: Main: 284540928 21:54:45 INFO - PROCESS | 12262 | 21:54:48 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58092/tests/kraken/driver.html?testName=imaging-darkroom) 21:54:48 INFO - PROCESS | 12262 | RSS: Main: 325025792 21:54:48 INFO - PROCESS | 12262 | 21:54:51 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58092/tests/kraken/driver.html?testName=imaging-desaturate) 21:54:52 INFO - PROCESS | 12262 | RSS: Main: 326057984 21:54:52 INFO - PROCESS | 12262 | 21:54:55 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58092/tests/kraken/driver.html?testName=json-parse-financial) 21:54:55 INFO - PROCESS | 12262 | RSS: Main: 322990080 21:54:55 INFO - PROCESS | 12262 | 21:54:57 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58092/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:54:58 INFO - PROCESS | 12262 | RSS: Main: 297955328 21:54:58 INFO - PROCESS | 12262 | 21:54:59 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:55:00 INFO - PROCESS | 12262 | RSS: Main: 301195264 21:55:00 INFO - PROCESS | 12262 | 21:55:02 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:55:03 INFO - PROCESS | 12262 | RSS: Main: 304451584 21:55:03 INFO - PROCESS | 12262 | 21:55:06 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:55:07 INFO - PROCESS | 12262 | RSS: Main: 324571136 21:55:07 INFO - PROCESS | 12262 | 21:55:10 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:55:10 INFO - PROCESS | 12262 | RSS: Main: 317394944 21:55:10 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | Cycle 1(1): loaded http://localhost:58092/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58092/tests/kraken/driver.html?testName=ai-astar) 21:55:12 INFO - PROCESS | 12262 | RSS: Main: 361766912 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | __start_tp_report 21:55:12 INFO - PROCESS | 12262 | _x_x_mozilla_page_load 21:55:12 INFO - PROCESS | 12262 | _x_x_mozilla_page_load_details 21:55:12 INFO - PROCESS | 12262 | |i|pagename|runs| 21:55:12 INFO - PROCESS | 12262 | |0;ai-astar;103;114;114;114;114;114;115;114;115;121 21:55:12 INFO - PROCESS | 12262 | |1;audio-beat-detection;147;110;116;180;154;124;111;103;141;108 21:55:12 INFO - PROCESS | 12262 | |2;audio-dft;184;186;187;185;186;187;186;188;186;187 21:55:12 INFO - PROCESS | 12262 | |3;audio-fft;83;60;61;92;60;60;60;59;85;58 21:55:12 INFO - PROCESS | 12262 | |4;audio-oscillator;87;71;94;70;69;70;89;67;69;92 21:55:12 INFO - PROCESS | 12262 | |5;imaging-gaussian-blur;90;87;91;88;91;87;88;130;135;89 21:55:12 INFO - PROCESS | 12262 | |6;imaging-darkroom;97;101;140;97;96;96;96;96;96;95 21:55:12 INFO - PROCESS | 12262 | |7;imaging-desaturate;85;84;81;80;82;84;87;86;85;83 21:55:12 INFO - PROCESS | 12262 | |8;json-parse-financial;53;54;54;54;56;57;58;57;56;57 21:55:12 INFO - PROCESS | 12262 | |9;json-stringify-tinderbox;66;72;69;77;73;70;70;72;71;72 21:55:12 INFO - PROCESS | 12262 | |10;stanford-crypto-aes;96;102;86;86;87;83;83;82;82;85 21:55:12 INFO - PROCESS | 12262 | |11;stanford-crypto-ccm;145;150;167;148;164;149;144;146;178;141 21:55:12 INFO - PROCESS | 12262 | |12;stanford-crypto-pbkdf2;167;170;166;167;167;166;224;203;165;167 21:55:12 INFO - PROCESS | 12262 | |13;stanford-crypto-sha256-iterative;68;68;68;67;67;67;68;67;67;67 21:55:12 INFO - PROCESS | 12262 | __end_tp_report 21:55:12 INFO - PROCESS | 12262 | __start_cc_report 21:55:12 INFO - PROCESS | 12262 | _x_x_mozilla_cycle_collect,1999 21:55:12 INFO - PROCESS | 12262 | __end_cc_report 21:55:12 INFO - PROCESS | 12262 | __startTimestamp1484978112733__endTimestamp 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | ------- Summary: start ------- 21:55:12 INFO - PROCESS | 12262 | Number of tests: 14 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:4.37 (3.8%) stddev-sans-first:2.29 21:55:12 INFO - PROCESS | 12262 | Values: 103.0 114.0 114.0 114.0 114.0 114.0 115.0 114.0 115.0 121.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:132.50 stddev:25.14 (19.0%) stddev-sans-first:25.85 21:55:12 INFO - PROCESS | 12262 | Values: 147.0 110.0 116.0 180.0 154.0 124.0 111.0 103.0 141.0 108.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#2] audio-dft Cycles:10 Average:186.20 Median:186.50 stddev:1.14 (0.6%) stddev-sans-first:0.88 21:55:12 INFO - PROCESS | 12262 | Values: 184.0 186.0 187.0 185.0 186.0 187.0 186.0 188.0 186.0 187.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#3] audio-fft Cycles:10 Average:67.80 Median:60.50 stddev:13.23 (21.9%) stddev-sans-first:12.84 21:55:12 INFO - PROCESS | 12262 | Values: 83.0 60.0 61.0 92.0 60.0 60.0 60.0 59.0 85.0 58.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.12 (14.1%) stddev-sans-first:11.29 21:55:12 INFO - PROCESS | 12262 | Values: 87.0 71.0 94.0 70.0 69.0 70.0 89.0 67.0 69.0 92.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:88.50 stddev:18.49 (20.9%) stddev-sans-first:19.40 21:55:12 INFO - PROCESS | 12262 | Values: 90.0 87.0 91.0 88.0 91.0 87.0 88.0 130.0 135.0 89.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:96.00 stddev:13.80 (14.4%) stddev-sans-first:14.56 21:55:12 INFO - PROCESS | 12262 | Values: 97.0 101.0 140.0 97.0 96.0 96.0 96.0 96.0 96.0 95.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.50 stddev:2.21 (2.6%) stddev-sans-first:2.30 21:55:12 INFO - PROCESS | 12262 | Values: 85.0 84.0 81.0 80.0 82.0 84.0 87.0 86.0 85.0 83.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.50 stddev:1.71 (3.0%) stddev-sans-first:1.54 21:55:12 INFO - PROCESS | 12262 | Values: 53.0 54.0 54.0 54.0 56.0 57.0 58.0 57.0 56.0 57.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:2.86 (4.0%) stddev-sans-first:2.33 21:55:12 INFO - PROCESS | 12262 | Values: 66.0 72.0 69.0 77.0 73.0 70.0 70.0 72.0 71.0 72.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.50 stddev:6.61 (7.7%) stddev-sans-first:6.20 21:55:12 INFO - PROCESS | 12262 | Values: 96.0 102.0 86.0 86.0 87.0 83.0 83.0 82.0 82.0 85.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:149.50 stddev:12.15 (8.1%) stddev-sans-first:12.52 21:55:12 INFO - PROCESS | 12262 | Values: 145.0 150.0 167.0 148.0 164.0 149.0 144.0 146.0 178.0 141.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:167.00 stddev:20.31 (12.2%) stddev-sans-first:21.27 21:55:12 INFO - PROCESS | 12262 | Values: 167.0 170.0 166.0 167.0 167.0 166.0 224.0 203.0 165.0 167.0 21:55:12 INFO - PROCESS | 12262 | 21:55:12 INFO - PROCESS | 12262 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 21:55:12 INFO - PROCESS | 12262 | Values: 68.0 68.0 68.0 67.0 67.0 67.0 68.0 67.0 67.0 67.0 21:55:12 INFO - PROCESS | 12262 | -------- Summary: end -------- 21:55:12 INFO - PROCESS | 12262 | 21:55:13 INFO - TEST-INFO | 12262: exit 0 21:55:13 INFO - TEST-OK | kraken | took 60098ms 21:55:13 INFO - SUITE-END | took 1179s 21:55:13 INFO - Completed test suite (00:19:39) 21:55:13 INFO - javascript benchmark 21:55:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.690282520335, 3025.818450892948, 3222.2588804574934, 3268.297316077043, 3326.18508465426, 5650.406707295737, 5708.828735137952, 5726.3700992890745, 5738.225339579156, 5802.303723553175, 5699.458551437607, 5723.313202415716, 5774.133879918015, 5775.653460792355, 5817.650940943547, 4254.9149017019645, 5203.427622961472, 5245.291885595453, 5249.86875328116, 5281.841544753664, 5182.922256166161, 5187.584993200554, 5188.584913206934, 5334.333208348957, 5377.247185394048, 4358.673099517533, 5285.867853303679, 5300.893982120355, 5307.469253074681, 5311.867203319928, 5122.3853137623355, 5131.7434128293435, 5171.2501687255135, 5204.4535323791215, 5223.425423203483, 4336.262835318003, 5171.327727395452, 5180.0, 5197.116490196638, 5236.136037553799, 5768.077107662753, 5773.971130144322, 5855.677937713424, 5856.76572937082, 5863.677497737623, 5112.718800465973, 6114.663693496856, 6158.168647232611, 6161.4146656067605, 6230.844228894269, 6133.089845874415, 6250.4374606285655, 6260.655663938482, 6261.342559031327, 6275.592086514362, 4912.926306105412, 5903.970480147572, 5905.409459054146, 5919.112133180014, 5919.852003699856, 6167.84580385494, 6205.906911396242, 6235.345288744707, 6266.968665156736, 6296.779612713624, 6209.9379006210265, 6216.067589861557, 6217.533684973645, 6220.471259942865, 6224.0, 6295.244570651551, 6305.8108256752375, 6312.873742525124, 6319.368063193644, 6323.841903952346, 6115.788007244199, 6152.753889844356, 6192.752289908443, 6231.501479881598, 6251.187345645037, 6272.996320588684, 6277.403646653561, 6300.653464059429, 6302.653354065479, 6313.589616674902, 4919.606431485473, 4950.3069570260195, 4953.578945789576, 4956.4547899731, 4963.528464795839, 4368.694191406571, 4411.338299255073, 4412.227860124465, 4432.800523976429, 4483.977580112144, 5708.800191993343, 5741.799037033684, 5762.88474230513, 5765.106408506688, 5770.365259821417, 6190.81427557174, 6245.6252624841745, 6254.749810007549, 6279.026750853625, 6289.308176100625, 4877.634177436636, 5842.678652674059, 5847.795327163529, 5853.1220316952795, 5856.707164641751, 4860.1737704590305, 5358.8660283492445, 5384.165454354581, 5432.945670543244, 5490.890182196334, 6140.718981675666, 6206.317305096526, 6264.185655864709, 6277.654728989949, 6281.937180628135, 6196.070589411444, 6240.063990401476, 6261.530385221217, 6265.342139075331, 6280.528960328085, 6114.755409783559, 6208.906866397098, 6277.905831412624, 6280.183576135073, 6373.681315934092, 6089.1779609753885, 6127.80043271483, 6238.0, 6245.656488893154, 6255.374462553708, 6148.0, 6194.38056194377, 6254.843628909313, 6292.150559674568, 6335.334789847, 6056.606320589148, 6076.240469941257, 6189.690515474298, 6202.937970620416, 6227.626342419469, 5957.4042595740075, 6069.969650151721, 6191.226096737908, 6215.502759779297, 6224.595401298901, 5902.793402230901, 5979.730912109053, 5994.340622531605, 6000.159977603226, 6091.238595175603, 6132.733139465431, 6171.413715697002, 6180.6600636965395, 6187.84530386744, 6190.969045154746, 6030.909536357046, 6034.900509069829, 6059.212302400836, 6060.1212824139375, 6086.756529738938], "value": 5686.072280944642, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.5264601137823, 471.67690132259406, 503.3205173016426, 508.278244892253, 517.9663321884079, 29356.26609334765, 33744.156396090046, 38813.417798733004, 39989.00027499314, 40156.0, 41283.9679008025, 41511.16977660445, 41579.16841663165, 41659.95850103749, 41719.0, 9257.074292570756, 9436.480993545352, 9436.528173591292, 9443.244540436748, 9450.196733277646, 8323.750287491355, 8568.742937711879, 9257.6296948122, 11309.547618095266, 11346.0, 11141.610043648474, 11197.27217730849, 11223.831642525369, 11224.831627525593, 11231.26996745213, 8326.292265157468, 11196.608118715847, 11268.549258029669, 11275.267107637977, 11277.32336059839, 8303.086660467343, 10885.020348168624, 11171.050460710849, 11252.718682033015, 11412.258203216765, 38837.0, 39251.60748392508, 39298.0, 39315.41026884599, 39318.803405983075, 3205.935881282361, 3215.180129067073, 3265.4775235962134, 3308.139883630249, 3344.1973926257765, 24185.395365115837, 28299.71700283012, 28941.565876511868, 29229.561556576667, 30064.947726829458, 2577.3814284571754, 3146.4179126861604, 3159.4628913084603, 3161.8419079045957, 3285.0801775502896, 495189.0, 495702.0, 496279.5186024083, 497223.0, 497867.0, 50000.49999499958, 57822.0, 58141.70929145412, 58154.41845581574, 58245.0, 60709.392906071254, 60781.69609152014, 60800.0, 60854.69572652197, 60927.08609370863, 15676.0, 15904.363825447084, 15911.204439777965, 15916.602084947737, 15917.044977301399, 11125.221234513507, 11317.49071291794, 12297.323647199311, 12377.6286711397, 12395.0, 15527.223638818014, 15987.520374388787, 15989.200539972955, 16183.757243641356, 16381.918090409472, 6227.501799856001, 6255.249370075529, 6272.18461599989, 6343.777967771106, 6392.232932048091, 847.3729440214197, 966.4346357380871, 980.843065109579, 985.265976847239, 1054.3252318516156, 28388.0, 28519.57220641692, 29057.273568160967, 29189.0, 29284.85357573241, 1030.8144533983955, 1036.4092467293722, 1074.4735079810994, 1075.704181350135, 1075.8063548561177, 12366.567170149001, 14489.623485768836, 14883.553493395215, 15086.281812538797, 15315.846841531666, 599397.0, 604773.0, 604821.0, 849780.0, 851931.0, 46462.53537464582, 54170.45829541733, 56266.0, 56315.59211019758, 59078.70460647669, 1316.7498175346846, 1326.4561529772748, 1334.046156997732, 1353.012301020241, 1361.748076605831, 29148.854255728587, 29887.252818679706, 29925.55111673283, 30192.201705039603, 30678.0, 48619.513804862916, 48894.26658600195, 48993.0, 49091.509084910125, 49126.01747965092, 485781.0, 488837.0, 488908.0, 489180.0, 490201.0, 37842.810785947, 38379.808100960436, 38510.42234366431, 38518.614813851505, 38523.0, 44813.551864480934, 45285.54714452814, 45843.0, 45846.08307833891, 46043.76978115088, 35971.99033118173, 36486.63513364832, 37381.62618373781, 38458.80770596129, 38644.420333695576, 37383.06542336354, 37590.0, 38553.80723096479, 38835.611643883196, 38875.0, 36283.818580906925, 36946.815265924575, 37288.44067338938, 37442.812785935894, 37595.624043760305, 31635.36729265356, 33377.33245335031, 33989.0, 34024.65975340215, 34229.48655770116, 21376.0, 24978.250652480456, 24990.75009249957, 25390.619140713272, 25412.74587254178], "value": 19659.554519522306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.701011614837, 342.37516532328505, 371.263042859923, 379.491481414904, 397.634176557567, 14396.712065758678, 14462.421503139874, 15497.457588984387, 19670.40988770339, 20138.19447222113, 18810.811891881076, 18814.905925470386, 19910.203591856345, 20083.799162008374, 20089.59820803583, 5264.657797243177, 5549.0289199390145, 5551.500364967153, 5562.694051827148, 5564.944350556503, 4500.8874778130485, 5457.863553411176, 5475.123980163175, 5501.312335958005, 5510.531604813596, 5492.670439773607, 5495.2856128703215, 5497.45025497451, 5498.80754173604, 5518.503334699896, 5462.289902312694, 5837.532997360201, 5945.8810823783715, 5966.940330596682, 6008.399160083956, 4686.437627484724, 5138.561202863204, 5329.067413202674, 5336.146216605324, 5337.146056630921, 12832.0, 13474.865251347459, 13485.730285394236, 13501.59495215145, 13504.932475337659, 2157.439065842892, 2167.9024443899903, 2174.3368272676826, 2865.2550336912554, 2934.193096898349, 9771.0, 9872.16086632637, 10225.64210252645, 10241.07830295277, 10535.63125290611, 1730.7663465432258, 1808.3489943620286, 1846.1599972012702, 1858.4703359542466, 1880.7931127575955, 113013.0, 113718.43140784244, 124217.75782242152, 125661.0, 127982.44035119339, 10738.624148154777, 11089.83365249522, 11371.39458519301, 11391.249245323594, 11562.0, 14436.206008669415, 14460.0, 14463.0, 14468.348924298221, 14478.85521144796, 8080.111187769341, 8341.541215233221, 8381.0, 8487.872681909776, 8520.105388934197, 8158.449894520022, 8161.79595510117, 8193.221643943816, 8308.0, 8339.416240863082, 3850.8844734658005, 4414.514403415622, 4516.503184649686, 4527.909441811211, 4576.359309696645, 1563.2965165675491, 1858.1545396844404, 1876.3807943378652, 1896.7249748786348, 1903.1911437638948, 756.1266736918867, 854.5385491834464, 887.7394100377479, 930.2697382554662, 937.5078084005816, 16403.83596164047, 18758.906205468884, 20936.581268374546, 21012.684809727867, 21070.841103739134, 720.909886264217, 883.1654086887829, 926.9860952085725, 940.3323640215386, 949.0746522140857, 10338.379697218192, 11061.391623460788, 11065.50205240766, 11072.39101849406, 11093.223474356879, 504641.0, 507532.0, 509840.0, 586325.0, 612835.0, 78801.0, 131003.17476189324, 133268.002939866, 135605.0, 141620.5837941628, 928.8699582058517, 962.8507581324906, 974.8099120671401, 978.4178413843752, 985.0199051943363, 15360.9231953844, 17676.11619418998, 18315.72526412078, 18969.24123035063, 19300.903495482995, 35945.820270898475, 48181.03637927292, 51433.48566514287, 53072.938541229734, 56647.43352566587, 66057.33942660512, 73362.63318683373, 78010.60994695217, 79461.60269198849, 80204.0, 7702.691892324245, 7760.581819629734, 7899.486533375312, 8045.195480452142, 8287.419880608299, 11599.478023488795, 11887.405629718307, 12252.860700828462, 12503.624891253277, 12618.936905315415, 10571.735706607396, 10685.679429617125, 10873.293235939638, 10914.454277286262, 10931.83602245951, 4910.6562540623245, 7873.858290547953, 10604.575816967235, 10694.572217111228, 11008.954149355799, 10729.839052414063, 11076.833847492133, 11182.217244793112, 11191.2725672831, 11224.071574948703, 7351.081114860643, 7809.297163255334, 7818.687252510063, 8056.597170141352, 8155.062167850773, 6215.285242197205, 9204.253758709025, 10554.0, 10893.078491742808, 11072.557097716324], "value": 8988.44835389341, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.1650526189549, 1461.0576178364954, 1578.2976575423934, 1749.8862573932686, 1763.964720705586, 7491.812704682372, 8065.193480651939, 8490.6179221935, 8514.91485085149, 8530.872036919443, 7570.5079169853925, 7718.845623087549, 8084.070331911832, 8318.70884519042, 8321.500709957407, 1952.5021119614478, 2291.117919600953, 2326.5114325991563, 2327.2320134355705, 2332.3352844439337, 2064.194963964055, 2304.0207911637485, 2329.534093181362, 2353.388119089032, 2358.1864256831363, 1769.867259955502, 2091.6444204485197, 2123.883186424746, 2123.9150433982622, 2125.51113243954, 2237.3288013595857, 2302.2747834432184, 2326.1044497868274, 2330.1844354475966, 2330.463993281555, 2329.9650505242435, 2339.239747082188, 2344.8358614896965, 2349.7415284318713, 2366.514864452783, 8403.201695838885, 8419.621117049748, 8506.74479765608, 8515.48907065578, 8522.70170544028, 4242.512111107231, 4900.926486102711, 5047.949520504784, 5066.974665126688, 5070.214116811863, 3159.3207460396125, 3381.6111147217894, 3411.9147021324416, 3510.3154884797473, 3581.928361432783, 1011.585250047477, 1049.0558497352367, 1079.2607064161075, 1091.1707102602, 1092.6230450494568, 10929.56281748729, 10974.945125274402, 10983.450827458675, 10987.725306867313, 11045.0, 4165.416841642173, 4438.556144385536, 4600.401947746838, 4689.320048592935, 4697.976510117427, 9431.717048488556, 9432.95283523578, 9441.952790236004, 9458.668946586975, 9461.90538094624, 5201.245819356172, 5213.635045546851, 5216.66091704038, 5219.973900130475, 5230.686158830483, 4517.5256598057385, 4736.715797052188, 4789.473157952622, 4798.184308667535, 4800.183968725325, 3755.3052685253315, 3771.6228377162065, 3815.6565909067763, 3856.305864944337, 3879.1078052048188, 1216.0940099625836, 1239.3741160713782, 1244.2783185752423, 1252.1297698099916, 1255.6205989649563, 750.7785203365021, 851.9446235994641, 882.5543100734088, 884.0187391994881, 892.5492626223845, 4765.523447655207, 5595.4124816894455, 5655.208270842086, 5813.651180929158, 6013.428724271187, 872.6727477196051, 892.5715656484793, 930.2325581395376, 954.2747511891013, 957.0716405087054, 3734.2158146788934, 3848.422736589478, 3859.710521710827, 3949.8025098744947, 4012.2376748417705, 9594.712158635251, 10116.949415252877, 10142.949285253526, 10180.796384072277, 10190.0, 9452.291078169164, 9953.303268771117, 9967.950160249153, 10019.0, 10085.243606729378, 7617.466777325645, 7816.257455541714, 7836.098848632366, 7843.9215607844335, 7852.646630901623, 5702.40124786908, 5715.5139663686905, 5728.140778883201, 5755.331356273286, 6045.697715114315, 9340.673076442292, 9417.387869788441, 9418.434893906388, 9431.292653050912, 9462.858057129011, 7223.530470519399, 9450.102240287162, 9461.148496635336, 9565.139137477661, 9588.520573971413, 7113.430925526049, 7277.890831637424, 7278.526895751759, 7284.890726639211, 7285.23505031964, 7708.768736937676, 7711.0, 7742.109657389472, 7876.369890408868, 7917.247861453153, 7197.028401165776, 7199.892001620085, 7215.242399547972, 7224.855502889807, 7268.89096663561, 5797.333306669787, 6738.124043874265, 6908.930910690829, 7002.2297547268745, 7014.894776578253, 6654.800355989328, 6689.76585819494, 6746.190457145174, 6749.730010799513, 6774.42417394527, 4743.146233677971, 4780.498047704941, 4886.267059941038, 4991.475895031114, 5088.465711100281, 4831.782569784438, 4844.563989240985, 4949.529794669501, 4978.253262010728, 5047.0], "value": 4395.396995178118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.4244836241872, 301.33254840528224, 308.3693846084756, 312.28642551769195, 313.3372916282064, 4652.511486293941, 4863.562279394854, 5306.575473962083, 5344.759485823138, 5357.866053348664, 4162.687798415116, 4599.8390056348035, 5121.743912804354, 5149.948500515003, 5295.708736019517, 4002.5797291284434, 4023.9798801006027, 4026.879193624196, 4029.899252518696, 4079.530853951789, 3601.405768048269, 3886.7667939923554, 3905.7892053463374, 3998.1204135090234, 4055.5944405559503, 3681.5582130144285, 3817.3701339279123, 3828.808559572024, 3891.571927088018, 4006.73956192848, 3316.6849149330774, 3843.8846834595006, 3970.2456533258587, 4011.2579172853116, 4027.637512623878, 3199.2641692410643, 3518.437050072002, 3525.012996361023, 3525.3301872644115, 3528.206153615416, 4554.929591546017, 5163.115462856031, 5163.870903227412, 5210.609204309692, 5228.320318358627, 3745.382011968022, 4526.95473045272, 4577.702449340783, 4602.0, 4649.18639238132, 5545.584081193927, 5600.719964001824, 5618.91571626426, 5637.379888212294, 5659.189688764517, 3281.573395458599, 3703.4444833275224, 3813.656770890633, 3826.349520581508, 3846.7114966377635, 5123.513266239738, 5636.661800291996, 5761.510271626917, 5778.515238447508, 5793.652380857162, 3221.64561898191, 3761.273077019194, 3829.310724069694, 3849.40334248192, 3856.2480316338606, 3895.104126051024, 3895.9935633148953, 3916.6279203475624, 3922.941155882664, 3950.3481925482547, 3568.411212150018, 3779.6787273082095, 3923.6664883485223, 4210.6841986851105, 4583.197940360424, 4681.852946028245, 5548.144039892236, 5556.6943818089585, 5564.554835613141, 5587.972060139673, 1215.343714394235, 1262.3688155922039, 1263.3051821498138, 1272.1985149355846, 1314.769915264825, 757.715671936074, 804.9597520124088, 819.4345901328064, 820.5281962871253, 825.3562221467265, 4326.502452217972, 4489.371487991619, 4645.790939407735, 4900.14247506692, 5397.865053373697, 4896.387951506062, 5268.4994925482015, 5687.544996400278, 5723.399043100498, 5796.304443466726, 4473.910521789546, 5244.29202057725, 5267.787529237653, 5345.35855697311, 5351.652142610796, 2477.380654836291, 2599.506093842164, 2810.4660114578164, 2821.5908693239367, 2845.2460098074025, 4573.771311434415, 4698.812047518061, 5459.697137194605, 5465.988792073479, 5554.833354999357, 5295.76169072385, 5562.499375056264, 5574.58190635704, 5590.357108932525, 5657.688827114466, 4707.646926480459, 4760.786703767191, 4798.832040878622, 5196.596918831894, 5558.777648893999, 4232.095556999301, 5419.403865574863, 5467.152591348347, 5517.641353312022, 5556.194351819045, 5343.4389389113585, 5348.518633322864, 5355.651882627617, 5363.0346537622045, 5369.1878766076525, 4519.977400112978, 5470.510917532592, 5528.037546671035, 5557.527610153183, 5575.776968921197, 3422.9046705054143, 3514.7715398499017, 3568.9663785781877, 3569.1790888095366, 3610.927781444409, 4656.627469802475, 4884.4382895966, 4891.095147397743, 5106.514881086291, 5333.306670132858, 4293.914121717486, 5176.689398636094, 5213.165893457029, 5255.97917593034, 5311.122880297482, 4605.894585299571, 4632.86101416958, 4877.853664390074, 5145.967342899608, 5277.540478295988, 4575.954240457686, 4658.277966915133, 4903.926441103315, 4964.925526117039, 5327.121025030827, 4917.483664215206, 5217.956408718195, 5388.838334849961, 5513.531349835149, 5547.639403438763, 4585.633149347977, 5175.844724658266, 5384.326959130109, 5399.325084364455, 5414.994677953773], "value": 3921.7000742140453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.6955632968646, 754.34748942165, 765.888946102815, 774.4965772248041, 794.7139029949217, 15549.922250388747, 16170.595735106628, 16213.270402831871, 16215.27031283592, 16284.0, 14820.333085011174, 14912.850871491282, 14942.252887355644, 14953.401863925457, 14961.850381496182, 631.3528633151011, 651.4983462733692, 804.0953926832314, 828.8425199212161, 831.600831600832, 652.3769799841164, 787.94484386093, 813.0649752784286, 814.2061490047216, 818.1899919080098, 866.527742380401, 870.0951010948606, 870.9695160669378, 871.3464901324007, 872.9960545372809, 866.2117473099449, 867.4318321499428, 867.4708427859001, 868.097178933908, 869.8738682890977, 493.83950216179886, 502.89538282288373, 614.1371373220633, 632.1213513216633, 637.1366797988705, 13983.510577129853, 14011.229382383965, 14066.085704429179, 14095.295235238196, 14128.081674691215, 12515.749685006249, 12580.182288151333, 12727.554535591209, 12806.231626102464, 12897.0, 12045.518179272818, 12073.637790866289, 12095.939520302341, 12113.0, 12223.75552488954, 4147.3778933159965, 4161.8959526012095, 4240.830366785325, 4389.231884420214, 4397.362382454558, 78226.6088669553, 83542.58228708878, 88253.11746882513, 88268.0, 88383.0, 9886.555105020292, 9949.154321882575, 10095.646652367132, 10118.797624047478, 10166.796664066826, 18731.812681873278, 18823.623527529646, 18831.90584047071, 18879.339223127125, 19026.334078307194, 7215.134183897861, 7239.8552028960175, 7300.233475485104, 7310.597917114504, 7435.8141046474275, 4033.2675305141975, 4056.452378928866, 4229.344451610005, 4417.624501917373, 4438.400815889878, 3349.363620912019, 3412.8805491807666, 3447.844846981892, 3453.8445769940413, 3475.391806433864, 565.4486875296553, 650.8145178624069, 653.0726368556661, 659.3373659472296, 662.9734810607619, 528.5190476666215, 543.8531596468931, 561.2351138831293, 567.3843879390824, 649.4544582550681, 7217.3533727248, 8261.710840120568, 8448.281896038905, 8938.195562399414, 9048.909510904807, 584.4944123333286, 615.1971676961518, 648.1412128929188, 663.0750103605443, 681.5297444763098, 4160.771157586361, 4371.672124590668, 4378.408914796461, 4428.512863585003, 4456.977715111404, 77674.22325776782, 99290.0141997156, 104729.0, 107130.0, 107766.46116769366, 89451.21097578012, 91014.54492727494, 92086.53956730307, 95111.0488895116, 95350.0, 52586.737066314425, 67160.0, 75439.58763505054, 75956.62021689856, 77954.0, 6837.01383657764, 7305.0, 8462.238398544161, 8621.956890215759, 8846.646134154562, 51747.0, 54172.72913635564, 54262.45737542574, 54803.17795232995, 54920.72539637276, 71289.28710712827, 74138.88791668238, 75989.62005189939, 76475.0, 77194.0, 11603.941980290383, 12011.819822702491, 12044.0, 12258.754824903273, 12437.813432798333, 13054.738905221653, 14098.872090232962, 14127.788083178555, 14684.706305874037, 14902.552923412315, 12195.268283902995, 13168.868311316764, 13172.143810652276, 13446.663833404244, 13814.792778108136, 13307.600771976857, 13340.533081342106, 13525.256110913993, 13809.51666691661, 13870.167789932637, 12574.685632859251, 12888.291143987171, 13051.0, 13200.339983001004, 13568.457261709422, 10316.43259620728, 10572.259941804308, 10729.302595331279, 11019.393933333744, 11101.50043248072, 10438.321509101885, 10836.499054027847, 11157.386343751172, 11484.253523520945, 11484.425778711198], "value": 7169.485999467875, "unit": "score"}], "name": "dromaeo_css", "value": 7063.390401604064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 115.0, 121.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 116.0, 180.0, 154.0, 124.0, 111.0, 103.0, 141.0, 108.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 187.0, 185.0, 186.0, 187.0, 186.0, 188.0, 186.0, 187.0], "value": 186.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 61.0, 92.0, 60.0, 60.0, 60.0, 59.0, 85.0, 58.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 94.0, 70.0, 69.0, 70.0, 89.0, 67.0, 69.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 91.0, 87.0, 88.0, 130.0, 135.0, 89.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 140.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 81.0, 80.0, 82.0, 84.0, 87.0, 86.0, 85.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 56.0, 57.0, 58.0, 57.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 69.0, 77.0, 73.0, 70.0, 70.0, 72.0, 71.0, 72.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 86.0, 86.0, 87.0, 83.0, 83.0, 82.0, 82.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 150.0, 167.0, 148.0, 164.0, 149.0, 144.0, 146.0, 178.0, 141.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 166.0, 167.0, 167.0, 166.0, 224.0, 203.0, 165.0, 167.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1468.1000000000004, "alertThreshold": 2.0}]} 21:55:13 INFO - Return code: 0 21:55:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:55:14 INFO - # TBPL SUCCESS # 21:55:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:55:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:55:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:55:14 INFO - 2017-01-20 21:55:14.063 screenresolution[12266:245904] starting screenresolution argv=screenresolution get 21:55:14 INFO - 2017-01-20 21:55:14.067 screenresolution[12266:245904] Display 0: 1600x1200x32@60 21:55:14 INFO - 2017-01-20 21:55:14.073 screenresolution[12267:245908] starting screenresolution argv=screenresolution list 21:55:14 INFO - Available Modes on Display 0 21:55:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:55:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:55:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:55:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:55:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:55:14 INFO - Intel Iris: 21:55:14 INFO - Chipset Model: Intel Iris 21:55:14 INFO - Type: GPU 21:55:14 INFO - Bus: Built-In 21:55:14 INFO - VRAM (Dynamic, Max): 1536 MB 21:55:14 INFO - Vendor: Intel (0x8086) 21:55:14 INFO - Device ID: 0x0a2e 21:55:14 INFO - Revision ID: 0x0009 21:55:14 INFO - Displays: 21:55:14 INFO - Display: 21:55:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:55:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:55:14 INFO - Display Serial Number: 1600x1200 60 21:55:14 INFO - Main Display: Yes 21:55:14 INFO - Mirror: Off 21:55:14 INFO - Online: Yes 21:55:14 INFO - Rotation: Supported 21:55:14 INFO - Return code: 0 21:55:14 INFO - Running post-action listener: _resource_record_post_action 21:55:14 INFO - [mozharness: 2017-01-21 05:55:14.319069Z] Finished run-tests step (success) 21:55:14 INFO - Running post-run listener: _resource_record_post_run 21:55:14 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 889344; Write bytes: 662782464; Read time: 16; Write time: 1963 21:55:14 INFO - TinderboxPrint: CPU usage
53.7% 21:55:14 INFO - TinderboxPrint: I/O read bytes / time
889,344 / 16 21:55:14 INFO - TinderboxPrint: I/O write bytes / time
662,782,464 / 1,963 21:55:14 INFO - TinderboxPrint: CPU idle
2,246.0 (46.3%) 21:55:14 INFO - TinderboxPrint: CPU system
986.4 (20.3%) 21:55:14 INFO - TinderboxPrint: CPU user
1,621.2 (33.4%) 21:55:14 INFO - TinderboxPrint: Swap in / out
352,092,160 / 0 21:55:14 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 339167744; Write bytes: 337581568; Read time: 27322; Write time: 703 21:55:14 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 864256; Write bytes: 307477504; Read time: 15; Write time: 1205 21:55:14 INFO - Running post-run listener: _upload_blobber_files 21:55:14 INFO - Blob upload gear active. 21:55:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:55:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:55:15 INFO - (blobuploader) - INFO - Open directory for files ... 21:55:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:55:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:55:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:55:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:55:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:55:16 INFO - (blobuploader) - INFO - Done attempting. 21:55:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:55:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:55:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:55:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:55:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:55:17 INFO - (blobuploader) - INFO - Done attempting. 21:55:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:55:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:55:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:55:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:55:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:55:19 INFO - (blobuploader) - INFO - Done attempting. 21:55:19 INFO - (blobuploader) - INFO - Iteration through files over. 21:55:19 INFO - Return code: 0 21:55:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:55:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:55:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0879e93703e20c5678d7b16430719298e6873bf118b27b7b622aa40e28e0ae4ed00844d40b3d8cbe33401367952a28ceb2e06f1ac22bee1bed9892183244eb10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143a05dfd840511cdea457cdcf7ee7cf5a8679c5e6daa891fdec191a900f07460709e4daabca159bbf59af782aea44b12568da4171816e762de5b044fd072674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/816d81a3e742144ca1f51cc484d052290207465fea39d28171a0f5097718667660eedc3c9de2122e4b3596e950749129a1ebd3a13036b2cf0cef54a0b8cbf105"} 21:55:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:55:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:55:19 INFO - Contents: 21:55:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0879e93703e20c5678d7b16430719298e6873bf118b27b7b622aa40e28e0ae4ed00844d40b3d8cbe33401367952a28ceb2e06f1ac22bee1bed9892183244eb10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143a05dfd840511cdea457cdcf7ee7cf5a8679c5e6daa891fdec191a900f07460709e4daabca159bbf59af782aea44b12568da4171816e762de5b044fd072674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/816d81a3e742144ca1f51cc484d052290207465fea39d28171a0f5097718667660eedc3c9de2122e4b3596e950749129a1ebd3a13036b2cf0cef54a0b8cbf105"} 21:55:19 INFO - Running post-run listener: copy_logs_to_upload_dir 21:55:19 INFO - Copying logs to upload dir... 21:55:19 INFO - mkdir: /builds/slave/test/build/upload/logs 21:55:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.342233 ========= master_lag: 0.89 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-20 21:55:20.459610) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 21:55:20.463024) ========= 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.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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/Mozilla-Aurora/sha512/0879e93703e20c5678d7b16430719298e6873bf118b27b7b622aa40e28e0ae4ed00844d40b3d8cbe33401367952a28ceb2e06f1ac22bee1bed9892183244eb10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143a05dfd840511cdea457cdcf7ee7cf5a8679c5e6daa891fdec191a900f07460709e4daabca159bbf59af782aea44b12568da4171816e762de5b044fd072674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/816d81a3e742144ca1f51cc484d052290207465fea39d28171a0f5097718667660eedc3c9de2122e4b3596e950749129a1ebd3a13036b2cf0cef54a0b8cbf105"} build_url:https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011429 build_url: 'https://queue.taskcluster.net/v1/task/cYE2_SerREaUUkMCbyb46g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0879e93703e20c5678d7b16430719298e6873bf118b27b7b622aa40e28e0ae4ed00844d40b3d8cbe33401367952a28ceb2e06f1ac22bee1bed9892183244eb10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143a05dfd840511cdea457cdcf7ee7cf5a8679c5e6daa891fdec191a900f07460709e4daabca159bbf59af782aea44b12568da4171816e762de5b044fd072674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/816d81a3e742144ca1f51cc484d052290207465fea39d28171a0f5097718667660eedc3c9de2122e4b3596e950749129a1ebd3a13036b2cf0cef54a0b8cbf105"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 21:55:20.501334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:55:20.501626) ========= 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.rTCuW7nPZz/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.mHcOimJICp/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.004645 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 21:55:20.550362) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 21:55:20.550663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 21:55:20.550984) ========= ========= Total master_lag: 1.03 =========