builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0009 starttime: 1478142238.94 results: success (0) buildid: 20161102171723 builduid: 3a4d89bea69d45f29d6b01ad291d8cc9 revision: ac55a6776435142feebf3c20bbabfee100686416 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:58.944467) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:58.944859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:58.945180) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:58.968330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:58.968613) ========= 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.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-02 20:03:58-- 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% 131M=0s 2016-11-02 20:03:59 (131 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:59.169136) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:59.172473) ========= 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.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.019641 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:59.220753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 20:03:59.221064) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ac55a6776435142feebf3c20bbabfee100686416 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ac55a6776435142feebf3c20bbabfee100686416 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-02 20:03:59,300 truncating revision to first 12 chars 2016-11-02 20:03:59,300 Setting DEBUG logging. 2016-11-02 20:03:59,300 attempt 1/10 2016-11-02 20:03:59,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ac55a6776435?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-02 20:04:00,831 unpacking tar archive at: mozilla-central-ac55a6776435/testing/mozharness/ program finished with exit code 0 elapsedTime=1.804202 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 20:04:01.052464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:04:01.052796) ========= script_repo_revision: ac55a6776435142feebf3c20bbabfee100686416 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:04:01.053183) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:04:01.053491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:04:01.070773) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-02 20:04:01.071060) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:01 INFO - MultiFileLogger online at 20161102 20:04:01 in /builds/slave/test 20:04:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:04:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:04:01 INFO - {'append_to_log': False, 20:04:01 INFO - 'base_work_dir': '/builds/slave/test', 20:04:01 INFO - 'blob_upload_branch': 'Firefox', 20:04:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:04:01 INFO - 'branch': 'Firefox', 20:04:01 INFO - 'buildbot_json_path': 'buildprops.json', 20:04:01 INFO - 'config_files': ('talos/mac_config.py',), 20:04:01 INFO - 'default_actions': ('clobber', 20:04:01 INFO - 'read-buildbot-config', 20:04:01 INFO - 'download-and-extract', 20:04:01 INFO - 'populate-webroot', 20:04:01 INFO - 'create-virtualenv', 20:04:01 INFO - 'install', 20:04:01 INFO - 'run-tests'), 20:04:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:04:01 INFO - 'download_minidump_stackwalk': True, 20:04:01 INFO - 'download_symbols': 'ondemand', 20:04:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:04:01 INFO - 'tooltool.py': '/tools/tooltool.py', 20:04:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:04:01 INFO - '/tools/misc-python/virtualenv.py')}, 20:04:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:04:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:04:01 INFO - 'installer_path': 'installer.exe', 20:04:01 INFO - 'log_level': 'info', 20:04:01 INFO - 'log_name': 'talos', 20:04:01 INFO - 'log_to_console': True, 20:04:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:04:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:04:01 INFO - 'opt_config_files': (), 20:04:01 INFO - 'pip_index': False, 20:04:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:04:01 INFO - 'cmd': ('bash', 20:04:01 INFO - '-c', 20:04:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:04:01 INFO - 'enabled': True, 20:04:01 INFO - 'halt_on_failure': False, 20:04:01 INFO - 'name': 'check_screen_resolution'},), 20:04:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:04:01 INFO - 'cmd': ('bash', 20:04:01 INFO - '-c', 20:04:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:04:01 INFO - 'enabled': True, 20:04:01 INFO - 'halt_on_failure': False, 20:04:01 INFO - 'name': 'check_screen_resolution'},), 20:04:01 INFO - 'run_cmd_checks_enabled': True, 20:04:01 INFO - 'sps_profile': False, 20:04:01 INFO - 'sps_profile_interval': 0, 20:04:01 INFO - 'suite': 'dromaeojs', 20:04:01 INFO - 'system_bits': '32', 20:04:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:04:01 INFO - 'title': 't-yosemite-r7-0009', 20:04:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:04:01 INFO - 'use_talos_json': True, 20:04:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:04:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:04:01 INFO - 'work_dir': 'build'} 20:04:01 INFO - [mozharness: 2016-11-03 03:04:01.270377Z] Running clobber step. 20:04:01 INFO - Running pre-action listener: _resource_record_pre_action 20:04:01 INFO - Running main action method: clobber 20:04:01 INFO - rmtree: /builds/slave/test/build 20:04:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:04:02 INFO - Running post-action listener: _resource_record_post_action 20:04:02 INFO - [mozharness: 2016-11-03 03:04:02.177999Z] Finished clobber step (success) 20:04:02 INFO - [mozharness: 2016-11-03 03:04:02.178161Z] Running read-buildbot-config step. 20:04:02 INFO - Running pre-action listener: _resource_record_pre_action 20:04:02 INFO - Running main action method: read_buildbot_config 20:04:02 INFO - Using buildbot properties: 20:04:02 INFO - { 20:04:02 INFO - "project": "", 20:04:02 INFO - "product": "firefox", 20:04:02 INFO - "script_repo_revision": "production", 20:04:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:04:02 INFO - "repository": "", 20:04:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:04:02 INFO - "buildid": "20161102171723", 20:04:02 INFO - "pgo_build": "False", 20:04:02 INFO - "basedir": "/builds/slave/test", 20:04:02 INFO - "buildnumber": 19, 20:04:02 INFO - "slavename": "t-yosemite-r7-0009", 20:04:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:04:02 INFO - "platform": "yosemite_r7", 20:04:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:04:02 INFO - "branch": "mozilla-central", 20:04:02 INFO - "repo_path": "mozilla-central", 20:04:02 INFO - "revision": "ac55a6776435142feebf3c20bbabfee100686416", 20:04:02 INFO - "stage_platform": "macosx64", 20:04:02 INFO - "builduid": "3a4d89bea69d45f29d6b01ad291d8cc9", 20:04:02 INFO - "slavebuilddir": "test" 20:04:02 INFO - } 20:04:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:04:02 INFO - Running post-action listener: _resource_record_post_action 20:04:02 INFO - [mozharness: 2016-11-03 03:04:02.181552Z] Finished read-buildbot-config step (success) 20:04:02 INFO - [mozharness: 2016-11-03 03:04:02.181685Z] Running download-and-extract step. 20:04:02 INFO - Running pre-action listener: _resource_record_pre_action 20:04:02 INFO - Running main action method: download_and_extract 20:04:02 INFO - mkdir: /builds/slave/test/build/tests 20:04:02 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')]} 20:04:02 INFO - trying https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:04:02 INFO - Downloading https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:04:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:04:03 INFO - Downloaded 1199 bytes. 20:04:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:04:03 INFO - Using the following test package requirements: 20:04:03 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:04:03 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:04:03 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:04:03 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'jsshell-mac.zip'], 20:04:03 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:04:03 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:04:03 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:04:03 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:04:03 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:04:03 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:04:03 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:04:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:04:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:04:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:04:03 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:04:04 INFO - Http code: 200 20:04:04 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 20:04:04 INFO - x-amz-cf-id: I8E32ZzgtkadeFPPNj6DMllz_Xeiy5zhO8teaZ3pxJwbeihjip0SbQ== 20:04:04 INFO - x-amz-version-id: uaf3.uAwB1T559tumwSlmQ3oSJ_RXYDW 20:04:04 INFO - Expected file size: 16389270 20:04:04 INFO - Obtained file size: 16389270 20:04:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:04:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:04:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:04:11 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:04:11 INFO - Http code: 200 20:04:11 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 20:04:11 INFO - x-amz-cf-id: I0HvRJPMpPivBVPy_86cXx5wA8_LJsDip_4UdB66zEBAcpni8RsH7g== 20:04:11 INFO - x-amz-version-id: uaf3.uAwB1T559tumwSlmQ3oSJ_RXYDW 20:04:12 INFO - Expected file size: 16389270 20:04:12 INFO - Obtained file size: 16389270 20:04:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:04:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:04:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:04:19 INFO - Http code: 200 20:04:19 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 20:04:19 INFO - x-amz-cf-id: cCg-nBqsvsZJoOhdKfr5awPrMDOW7zWzkrFL9LsztQlEVHm4E98arw== 20:04:19 INFO - x-amz-version-id: SX5O70OVlToACv.KCZkEyPpy6O3C1rl4 20:04:19 INFO - Expected file size: 14409038 20:04:19 INFO - Obtained file size: 14409038 20:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:19 INFO - trying https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:04:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:04:22 INFO - Downloaded 111881450 bytes. 20:04:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:22 INFO - mkdir: /builds/slave/test/properties 20:04:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:04:22 INFO - Writing to file /builds/slave/test/properties/build_url 20:04:22 INFO - Contents: 20:04:22 INFO - build_url:https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:23 INFO - Running post-action listener: _resource_record_post_action 20:04:23 INFO - Running post-action listener: set_extra_try_arguments 20:04:23 INFO - [mozharness: 2016-11-03 03:04:23.174843Z] Finished download-and-extract step (success) 20:04:23 INFO - [mozharness: 2016-11-03 03:04:23.174991Z] Running populate-webroot step. 20:04:23 INFO - Running pre-action listener: _resource_record_pre_action 20:04:23 INFO - Running main action method: populate_webroot 20:04:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:04:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:04:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:04:23 INFO - '--tppagecycles', 20:04:23 INFO - '7'], 20:04:23 INFO - 'tests': ['tsvgm']}}, 20:04:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:04:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:04:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:04:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:04:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['damp', 'tps']}, 20:04:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'tests': ['damp', 'tps']}, 20:04:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['dromaeo_dom']}, 20:04:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:04:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['basic_compositor_video']}, 20:04:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:04:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'other-e10s': {'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['a11yr', 20:04:23 INFO - 'ts_paint', 20:04:23 INFO - 'tpaint', 20:04:23 INFO - 'sessionrestore', 20:04:23 INFO - 'sessionrestore_no_auto_restore', 20:04:23 INFO - 'tabpaint']}, 20:04:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['tsvgx', 20:04:23 INFO - 'tsvgr_opacity', 20:04:23 INFO - 'tart', 20:04:23 INFO - 'tscrollx', 20:04:23 INFO - 'cart']}, 20:04:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:04:23 INFO - 'tsvgr_opacity', 20:04:23 INFO - 'tart', 20:04:23 INFO - 'tscrollx', 20:04:23 INFO - 'cart']}, 20:04:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'talos_options': ['--disable-e10s'], 20:04:23 INFO - 'tests': ['tp5o']}, 20:04:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'tests': ['tp5o']}, 20:04:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'talos_options': ['--disable-e10s', 20:04:23 INFO - '--xperf_path', 20:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:23 INFO - 'tests': ['tp5n']}, 20:04:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:23 INFO - 'talos_options': ['--xperf_path', 20:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:23 INFO - 'tests': ['tp5n']}}, 20:04:23 INFO - 'talos.zip': {'path': '', 20:04:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:04:23 INFO - Running post-action listener: _resource_record_post_action 20:04:23 INFO - [mozharness: 2016-11-03 03:04:23.193997Z] Finished populate-webroot step (success) 20:04:23 INFO - [mozharness: 2016-11-03 03:04:23.194107Z] Running create-virtualenv step. 20:04:23 INFO - Running pre-action listener: _resource_record_pre_action 20:04:23 INFO - Running main action method: create_virtualenv 20:04:23 INFO - Creating virtualenv /builds/slave/test/build/venv 20:04:23 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 20:04:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:04:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:04:23 INFO - Using real prefix '/tools/python27' 20:04:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:04:24 INFO - Installing distribute.............................................................................................................................................................................................done. 20:04:26 INFO - Installing pip.................done. 20:04:26 INFO - Return code: 0 20:04:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:04:27 INFO - Reading from file tmpfile_stdout 20:04:27 INFO - Output received: 20:04:27 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) 20:04:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:04:27 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')]} 20:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:27 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')]} 20:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:27 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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:27 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 20:04:27 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 20:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:27 INFO - 'HOME': '/Users/cltbld', 20:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:27 INFO - 'LOGNAME': 'cltbld', 20:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:27 INFO - 'MOZ_NO_REMOTE': '1', 20:04:27 INFO - 'NO_EM_RESTART': '1', 20:04:27 INFO - 'PAGER': '/bin/cat', 20:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:27 INFO - 'PWD': '/builds/slave/test', 20:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:27 INFO - 'SHELL': '/bin/bash', 20:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:27 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:27 INFO - 'USER': 'cltbld', 20:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:27 INFO - 'XPC_FLAGS': '0x0', 20:04:27 INFO - 'XPC_SERVICE_NAME': '0', 20:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:27 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 20:04:27 INFO - Cleaning up... 20:04:27 INFO - Return code: 0 20:04:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:04:27 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')]} 20:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:27 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')]} 20:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:27 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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:27 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 20:04:27 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 20:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:27 INFO - 'HOME': '/Users/cltbld', 20:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:27 INFO - 'LOGNAME': 'cltbld', 20:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:27 INFO - 'MOZ_NO_REMOTE': '1', 20:04:27 INFO - 'NO_EM_RESTART': '1', 20:04:27 INFO - 'PAGER': '/bin/cat', 20:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:27 INFO - 'PWD': '/builds/slave/test', 20:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:27 INFO - 'SHELL': '/bin/bash', 20:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:27 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:27 INFO - 'USER': 'cltbld', 20:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:27 INFO - 'XPC_FLAGS': '0x0', 20:04:27 INFO - 'XPC_SERVICE_NAME': '0', 20:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:27 INFO - Downloading/unpacking psutil>=3.1.1 20:04:27 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 20:04:27 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 20:04:27 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 20:04:27 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 20:04:27 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 20:04:27 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 20:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:04:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:31 INFO - Installing collected packages: psutil 20:04:31 INFO - Running setup.py install for psutil 20:04:31 INFO - building 'psutil._psutil_osx' extension 20:04:31 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 20:04:31 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 20:04:31 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 20:04:31 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 20:04:31 INFO - building 'psutil._psutil_posix' extension 20:04:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:04:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:04:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:04:31 INFO - ^ 20:04:31 INFO - 1 warning generated. 20:04:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:04:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:32 INFO - Successfully installed psutil 20:04:32 INFO - Cleaning up... 20:04:32 INFO - Return code: 0 20:04:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:04: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 mozsystemmonitor==0.3 20:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:32 INFO - 'HOME': '/Users/cltbld', 20:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:32 INFO - 'LOGNAME': 'cltbld', 20:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:32 INFO - 'MOZ_NO_REMOTE': '1', 20:04:32 INFO - 'NO_EM_RESTART': '1', 20:04:32 INFO - 'PAGER': '/bin/cat', 20:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:32 INFO - 'PWD': '/builds/slave/test', 20:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:32 INFO - 'SHELL': '/bin/bash', 20:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:32 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:32 INFO - 'USER': 'cltbld', 20:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:32 INFO - 'XPC_FLAGS': '0x0', 20:04:32 INFO - 'XPC_SERVICE_NAME': '0', 20:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:04:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:04:35 INFO - Installing collected packages: mozsystemmonitor 20:04:35 INFO - Running setup.py install for mozsystemmonitor 20:04:35 INFO - Successfully installed mozsystemmonitor 20:04:35 INFO - Cleaning up... 20:04:35 INFO - Return code: 0 20:04:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:04:35 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')]} 20:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:35 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')]} 20:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:35 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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:35 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 20:04:35 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 20:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:35 INFO - 'HOME': '/Users/cltbld', 20:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:35 INFO - 'LOGNAME': 'cltbld', 20:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:35 INFO - 'MOZ_NO_REMOTE': '1', 20:04:35 INFO - 'NO_EM_RESTART': '1', 20:04:35 INFO - 'PAGER': '/bin/cat', 20:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:35 INFO - 'PWD': '/builds/slave/test', 20:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:35 INFO - 'SHELL': '/bin/bash', 20:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:35 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:35 INFO - 'USER': 'cltbld', 20:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:35 INFO - 'XPC_FLAGS': '0x0', 20:04:35 INFO - 'XPC_SERVICE_NAME': '0', 20:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:35 INFO - Downloading/unpacking jsonschema==2.5.1 20:04:35 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 20:04:35 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 20:04:35 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 20:04:35 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 20:04:35 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 20:04:35 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 20:04:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:04:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:38 INFO - Downloading functools32-3.2.3-2.tar.gz 20:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:04:38 INFO - warning: no files found matching '*.txt' 20:04:38 INFO - no previously-included directories found matching 'build' 20:04:38 INFO - no previously-included directories found matching 'dist' 20:04:38 INFO - no previously-included directories found matching '.git*' 20:04:38 INFO - Installing collected packages: jsonschema, functools32 20:04:38 INFO - Running setup.py install for functools32 20:04:38 INFO - warning: no files found matching '*.txt' 20:04:38 INFO - no previously-included directories found matching 'build' 20:04:38 INFO - no previously-included directories found matching 'dist' 20:04:38 INFO - no previously-included directories found matching '.git*' 20:04:38 INFO - Successfully installed jsonschema functools32 20:04:38 INFO - Cleaning up... 20:04:38 INFO - Return code: 0 20:04:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:38 INFO - 'HOME': '/Users/cltbld', 20:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:38 INFO - 'LOGNAME': 'cltbld', 20:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:38 INFO - 'MOZ_NO_REMOTE': '1', 20:04:38 INFO - 'NO_EM_RESTART': '1', 20:04:38 INFO - 'PAGER': '/bin/cat', 20:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:38 INFO - 'PWD': '/builds/slave/test', 20:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:38 INFO - 'SHELL': '/bin/bash', 20:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:38 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:38 INFO - 'USER': 'cltbld', 20:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:38 INFO - 'XPC_FLAGS': '0x0', 20:04:38 INFO - 'XPC_SERVICE_NAME': '0', 20:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:04:39 INFO - Cleaning up... 20:04:39 INFO - Return code: 0 20:04:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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', '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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:04: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 blobuploader==1.2.4 20:04:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:39 INFO - 'HOME': '/Users/cltbld', 20:04:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:39 INFO - 'LOGNAME': 'cltbld', 20:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:39 INFO - 'MOZ_NO_REMOTE': '1', 20:04:39 INFO - 'NO_EM_RESTART': '1', 20:04:39 INFO - 'PAGER': '/bin/cat', 20:04:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:39 INFO - 'PWD': '/builds/slave/test', 20:04:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:39 INFO - 'SHELL': '/bin/bash', 20:04:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:39 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:39 INFO - 'USER': 'cltbld', 20:04:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:39 INFO - 'XPC_FLAGS': '0x0', 20:04:39 INFO - 'XPC_SERVICE_NAME': '0', 20:04:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:39 INFO - Downloading/unpacking blobuploader==1.2.4 20:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:41 INFO - Downloading blobuploader-1.2.4.tar.gz 20:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:04:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:04:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04:42 INFO - Downloading docopt-0.6.1.tar.gz 20:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:04:43 INFO - Installing collected packages: blobuploader, requests, docopt 20:04:43 INFO - Running setup.py install for blobuploader 20:04:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:04:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:04:43 INFO - Running setup.py install for requests 20:04:43 INFO - Running setup.py install for docopt 20:04:43 INFO - Successfully installed blobuploader requests docopt 20:04:43 INFO - Cleaning up... 20:04:43 INFO - Return code: 0 20:04:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:04:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:04:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:43 INFO - 'HOME': '/Users/cltbld', 20:04:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:43 INFO - 'LOGNAME': 'cltbld', 20:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:43 INFO - 'MOZ_NO_REMOTE': '1', 20:04:43 INFO - 'NO_EM_RESTART': '1', 20:04:43 INFO - 'PAGER': '/bin/cat', 20:04:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:43 INFO - 'PWD': '/builds/slave/test', 20:04:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:43 INFO - 'SHELL': '/bin/bash', 20:04:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:43 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:43 INFO - 'USER': 'cltbld', 20:04:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:43 INFO - 'XPC_FLAGS': '0x0', 20:04:43 INFO - 'XPC_SERVICE_NAME': '0', 20:04:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-1RfZZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-RpvPHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-YEUnWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-uG433j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-HzKGNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-_y5maQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-oAfZXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-TMgTkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-BgDwM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-zAS2q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-GsRpJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-DA8hEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FvOZLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2gwkwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-zeCqGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-1jt9b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:45 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-CLGMOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:04:45 INFO - Running setup.py install for manifestparser 20:04:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:04:46 INFO - Running setup.py install for mozcrash 20:04:46 INFO - Running setup.py install for mozdebug 20:04:46 INFO - Running setup.py install for mozdevice 20:04:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:04:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:04:46 INFO - Running setup.py install for mozfile 20:04:46 INFO - Running setup.py install for mozhttpd 20:04:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:04:46 INFO - Running setup.py install for mozinfo 20:04:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Running setup.py install for mozInstall 20:04:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Running setup.py install for mozleak 20:04:47 INFO - Running setup.py install for mozlog 20:04:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Running setup.py install for moznetwork 20:04:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Running setup.py install for mozprocess 20:04:47 INFO - Running setup.py install for mozprofile 20:04:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:04:47 INFO - Running setup.py install for mozrunner 20:04:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:04:48 INFO - Running setup.py install for mozscreenshot 20:04:48 INFO - Running setup.py install for moztest 20:04:48 INFO - Running setup.py install for mozversion 20:04:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:04:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:04:48 INFO - Cleaning up... 20:04:48 INFO - Return code: 0 20:04:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:48 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 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:48 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 20:04:48 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 20:04:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:48 INFO - 'HOME': '/Users/cltbld', 20:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:48 INFO - 'LOGNAME': 'cltbld', 20:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:48 INFO - 'MOZ_NO_REMOTE': '1', 20:04:48 INFO - 'NO_EM_RESTART': '1', 20:04:48 INFO - 'PAGER': '/bin/cat', 20:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:48 INFO - 'PWD': '/builds/slave/test', 20:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:48 INFO - 'SHELL': '/bin/bash', 20:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:48 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:48 INFO - 'USER': 'cltbld', 20:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:48 INFO - 'XPC_FLAGS': '0x0', 20:04:48 INFO - 'XPC_SERVICE_NAME': '0', 20:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:48 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8UtjLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:48 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Lyiif8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-bOTELX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-AbQvus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Lw9_Ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-d5qQI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-vt44a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-DuVaPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:49 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)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-jShYnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-gN8k08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:49 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-oNzDq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:50 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)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-kt2feB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:50 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)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-0BVIYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:50 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)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-tqwOtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2UNkfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:50 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)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-nOL3OJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:50 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Xi7CEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:50 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)) 20:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:50 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)) 20:04:50 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)) 20:04:50 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)) 20:04:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:53 INFO - Downloading blessings-1.6.tar.gz 20:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:04:53 INFO - Installing collected packages: blessings 20:04:53 INFO - Running setup.py install for blessings 20:04:53 INFO - Successfully installed blessings 20:04:53 INFO - Cleaning up... 20:04:53 INFO - Return code: 0 20:04:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:53 INFO - Reading from file tmpfile_stdout 20:04:53 INFO - Current package versions: 20:04:53 INFO - blessings == 1.6 20:04:53 INFO - blobuploader == 1.2.4 20:04:53 INFO - docopt == 0.6.1 20:04:53 INFO - functools32 == 3.2.3-2 20:04:53 INFO - jsonschema == 2.5.1 20:04:53 INFO - manifestparser == 1.1 20:04:53 INFO - mozInstall == 1.12 20:04:53 INFO - mozcrash == 0.17 20:04:53 INFO - mozdebug == 0.1 20:04:53 INFO - mozdevice == 0.48 20:04:53 INFO - mozfile == 1.2 20:04:53 INFO - mozhttpd == 0.7 20:04:53 INFO - mozinfo == 0.9 20:04:53 INFO - mozleak == 0.1 20:04:53 INFO - mozlog == 3.4 20:04:53 INFO - moznetwork == 0.27 20:04:53 INFO - mozprocess == 0.23 20:04:53 INFO - mozprofile == 0.28 20:04:53 INFO - mozrunner == 6.12 20:04:53 INFO - mozscreenshot == 0.1 20:04:53 INFO - mozsystemmonitor == 0.3 20:04:53 INFO - moztest == 0.7 20:04:53 INFO - mozversion == 1.4 20:04:53 INFO - psutil == 3.1.1 20:04:53 INFO - requests == 1.2.3 20:04:53 INFO - wsgiref == 0.1.2 20:04:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:53 INFO - 'HOME': '/Users/cltbld', 20:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:53 INFO - 'LOGNAME': 'cltbld', 20:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:53 INFO - 'MOZ_NO_REMOTE': '1', 20:04:53 INFO - 'NO_EM_RESTART': '1', 20:04:53 INFO - 'PAGER': '/bin/cat', 20:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:53 INFO - 'PWD': '/builds/slave/test', 20:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:53 INFO - 'SHELL': '/bin/bash', 20:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:53 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:53 INFO - 'USER': 'cltbld', 20:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:53 INFO - 'XPC_FLAGS': '0x0', 20:04:53 INFO - 'XPC_SERVICE_NAME': '0', 20:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 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)) 20:04:54 INFO - Cleaning up... 20:04:54 INFO - Return code: 0 20:04:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1e84a6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, '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.TdPMb15mAn/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.lDugaCIGvR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:54 INFO - 'HOME': '/Users/cltbld', 20:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:54 INFO - 'LOGNAME': 'cltbld', 20:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:54 INFO - 'MOZ_NO_REMOTE': '1', 20:04:54 INFO - 'NO_EM_RESTART': '1', 20:04:54 INFO - 'PAGER': '/bin/cat', 20:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:54 INFO - 'PWD': '/builds/slave/test', 20:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:54 INFO - 'SHELL': '/bin/bash', 20:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:04:54 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:54 INFO - 'USER': 'cltbld', 20:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:54 INFO - 'XPC_FLAGS': '0x0', 20:04:54 INFO - 'XPC_SERVICE_NAME': '0', 20:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:04:54 INFO - Cleaning up... 20:04:54 INFO - Return code: 0 20:04:54 INFO - Running post-action listener: _resource_record_post_action 20:04:54 INFO - Running post-action listener: _start_resource_monitoring 20:04:54 INFO - Starting resource monitoring. 20:04:54 INFO - [mozharness: 2016-11-03 03:04:54.447156Z] Finished create-virtualenv step (success) 20:04:54 INFO - [mozharness: 2016-11-03 03:04:54.448185Z] Running install step. 20:04:54 INFO - Running pre-action listener: _resource_record_pre_action 20:04:54 INFO - Running main action method: install 20:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:54 INFO - Reading from file tmpfile_stdout 20:04:54 INFO - Detecting whether we're running mozinstall >=1.0... 20:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:04:54 INFO - Reading from file tmpfile_stdout 20:04:54 INFO - Output received: 20:04:54 INFO - Usage: mozinstall [options] installer 20:04:54 INFO - Options: 20:04:54 INFO - -h, --help show this help message and exit 20:04:54 INFO - -d DEST, --destination=DEST 20:04:54 INFO - Directory to install application into. [default: 20:04:54 INFO - "/builds/slave/test"] 20:04:54 INFO - --app=APP Application being installed. [default: firefox] 20:04:54 INFO - mkdir: /builds/slave/test/build/application 20:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:05:29 INFO - Reading from file tmpfile_stdout 20:05:29 INFO - Output received: 20:05:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:05:29 INFO - Running post-action listener: _resource_record_post_action 20:05:29 INFO - [mozharness: 2016-11-03 03:05:29.267658Z] Finished install step (success) 20:05:29 INFO - [mozharness: 2016-11-03 03:05:29.267788Z] Running run-tests step. 20:05:29 INFO - Running pre-action listener: _resource_record_pre_action 20:05:29 INFO - Running main action method: run_tests 20:05:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:05:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:05:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:05:29 INFO - 2016-11-02 20:05:29.282 screenresolution[1939:16910] starting screenresolution argv=screenresolution get 20:05:29 INFO - 2016-11-02 20:05:29.284 screenresolution[1939:16910] Display 0: 1600x1200x32@60 20:05:29 INFO - 2016-11-02 20:05:29.290 screenresolution[1940:16912] starting screenresolution argv=screenresolution list 20:05:29 INFO - Available Modes on Display 0 20:05:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:05:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:05:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:05:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:05:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:05:29 INFO - Intel Iris: 20:05:29 INFO - Chipset Model: Intel Iris 20:05:29 INFO - Type: GPU 20:05:29 INFO - Bus: Built-In 20:05:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:05:29 INFO - Vendor: Intel (0x8086) 20:05:29 INFO - Device ID: 0x0a2e 20:05:29 INFO - Revision ID: 0x0009 20:05:29 INFO - Displays: 20:05:29 INFO - Display: 20:05:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:05:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:05:29 INFO - Display Serial Number: 1600x1200 60 20:05:29 INFO - Main Display: Yes 20:05:29 INFO - Mirror: Off 20:05:29 INFO - Online: Yes 20:05:29 INFO - Rotation: Supported 20:05:29 INFO - Return code: 0 20:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:05:29 INFO - Python 2.7.3 20:05:29 INFO - Return code: 0 20:05:29 INFO - grabbing minidump binary from tooltool 20:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:29 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 0x1048f9d30>, '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 0x10471fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048f1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:05:29 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 20:05:29 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 20:05:29 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 20:05:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:05:29 INFO - Return code: 0 20:05:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:05:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:05:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:05:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:05:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 20:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --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 20:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TdPMb15mAn/Render', 20:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:29 INFO - 'HOME': '/Users/cltbld', 20:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:29 INFO - 'LOGNAME': 'cltbld', 20:05:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:05:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:29 INFO - 'MOZ_NO_REMOTE': '1', 20:05:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:05:29 INFO - 'NO_EM_RESTART': '1', 20:05:29 INFO - 'PAGER': '/bin/cat', 20:05:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:29 INFO - 'PWD': '/builds/slave/test', 20:05:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:29 INFO - 'SHELL': '/bin/bash', 20:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDugaCIGvR/Listeners', 20:05:29 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:29 INFO - 'USER': 'cltbld', 20:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:29 INFO - 'XPC_FLAGS': '0x0', 20:05:29 INFO - 'XPC_SERVICE_NAME': '0', 20:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 20:05:29 INFO - mozversion application_buildid: 20161102171723 20:05:29 INFO - mozversion application_changeset: ac55a6776435142feebf3c20bbabfee100686416 20:05:29 INFO - mozversion application_display_name: Nightly 20:05:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:05:29 INFO - mozversion application_name: Firefox 20:05:29 INFO - mozversion application_remotingname: firefox 20:05:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:05:29 INFO - mozversion application_vendor: Mozilla 20:05:29 INFO - mozversion application_version: 52.0a1 20:05:29 INFO - mozversion platform_buildid: 20161102171723 20:05:29 INFO - mozversion platform_changeset: ac55a6776435142feebf3c20bbabfee100686416 20:05:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:05:29 INFO - mozversion platform_version: 52.0a1 20:05:29 INFO - using testdate: 1478142329 20:05:29 INFO - actual date: 1478142329 20:05:29 INFO - starting webserver on 'localhost:49433' 20:05:29 INFO - SUITE-START | Running 2 tests 20:05:29 INFO - TEST-START | dromaeo_css 20:05:29 INFO - operating with platform_type : mac_ 20:05:29 INFO - Initialising browser for dromaeo_css test... 20:05:29 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpsdc166/profile http://localhost:49433/getInfo.html) 20:05:32 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 20:05:32 INFO - PROCESS | 1950 | colorDepth:24 20:05:32 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 20:05:32 INFO - PROCESS | 1950 | __metrics 20:05:32 INFO - PROCESS | 1950 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:05:32 INFO - PROCESS | 1950 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:05:32 INFO - TEST-INFO | 1950: exit 0 20:05:32 INFO - Browser initialized. 20:05:32 INFO - Running cycle 1/1 for dromaeo_css test... 20:05:32 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpsdc166/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:05:44 INFO - PROCESS | 1952 | RSS: Main: 240648192 20:05:44 INFO - PROCESS | 1952 | 20:06:35 INFO - PROCESS | 1952 | 1478142395945 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:08:50 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49433/tests/dromaeo/cssquery-ext.html) 20:08:52 INFO - PROCESS | 1952 | RSS: Main: 388771840 20:08:52 INFO - PROCESS | 1952 | 20:11:55 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-ext.html (next: http://localhost:49433/tests/dromaeo/cssquery-jquery.html) 20:11:56 INFO - PROCESS | 1952 | RSS: Main: 375361536 20:11:56 INFO - PROCESS | 1952 | 20:14:58 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49433/tests/dromaeo/cssquery-mootools.html) 20:14:59 INFO - PROCESS | 1952 | RSS: Main: 344924160 20:14:59 INFO - PROCESS | 1952 | 20:18:01 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49433/tests/dromaeo/cssquery-prototype.html) 20:18:02 INFO - PROCESS | 1952 | RSS: Main: 345448448 20:18:02 INFO - PROCESS | 1952 | 20:21:05 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49433/tests/dromaeo/cssquery-yui.html) 20:21:06 INFO - PROCESS | 1952 | RSS: Main: 329478144 20:21:06 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49433/tests/dromaeo/cssquery-yui.html (next: http://localhost:49433/tests/dromaeo/cssquery-dojo.html) 20:24:09 INFO - PROCESS | 1952 | RSS: Main: 477368320 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | __start_tp_report 20:24:09 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 20:24:09 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 20:24:09 INFO - PROCESS | 1952 | |i|pagename|runs| 20:24:09 INFO - PROCESS | 1952 | |0;dojo.html;2995.8651860666273;3303.4384154693707;3303.7191838693693;3334.216459132102;3356.9664303356976;5506.669599824013;5615.943840561593;5621.57838162138;5690.772369105231;5698.886022279557;5617.578681598876;5653.632513886594;5654.632448890829;5671.2060311556415;5673.943260567393;5118.129917913954;5155.046316431453;5161.122609156452;5162.199859021857;5195.636305458619;5242.528172464477;5243.528082472577;5250.973745131269;5260.842174734745;5263.789448422059;5124.180131179012;5173.405058418273;5193.091209038422;5230.686158830483;5252.65857719247;5227.451117632631;5235.18854577543;5243.475652434726;5272.340957380327;5304.73476326186;4991.101601711727;5114.514121158484;5152.149895267276;5171.5862730981435;5181.533661970403;5490.956718223525;5538.75075621598;5549.639273447254;5591.217229587861;5607.074832652607;6166.414190651881;6202.286737025254;6225.470834979033;6232.594881332699;6259.37406259375;5865.85335366615;6137.079438084278;6198.535109866733;6257.43683068526;6291.276503202099;5690.374058853523;5709.885802283931;5738.68437235956;5759.222504961776;5767.769289228384;6200.241736507229;6241.18864547615;6251.749930002749;6253.74985000604;6254.624722516573;5704.22992895962;6106.908396374058;6135.96932015346;6142.6314421134875;6148.385161483816;5169.4572069932865;6095.35998720128;6111.57218994675;6137.6654897398075;6146.416090471398;6147.323794382614;6171;6176.135341052166;6180.907286390708;6186.133941248228;6142.385761423822;6204.13142160098;6227.750889964442;6231.4391704745885;6232.594881332699;4814.9518504815205;4844.781984810692;4899.338589290472;4924.901501969941;4951.653384263067;3743.21392507571;4399.076193999296;4414.161309351213;4445.688801783844;4470.687051906335;5743.368229494752;5746.166805813134;5753.654780713171;5770.6249093808765;5812.244408226988;6217.191765070603;6265.310815810257;6280.183576135073;6288.151099601495;6288.245410550671;5599;5639.548836093103;5657.915131273035;5706.9714651426475;5707.828765137053;5322.707251101149;5334.51989320963;5357.491038351351;5373.919391209135;5427.565794736412;6298.559100862895;6322.494200464043;6326.588771729915;6339.524535659752;6339.96830015847;6138.570300078952;6147.538934580014;6261.937380626136;6281.748730050746;6283.3402492737605;6198.101275315144;6234.937650623436;6257.7184026718;6264.342244064308;6288.685565721604;6106.339326077157;6127.189528148541;6137.880059131387;6145.770845830941;6148.200733904742;6196.938030619636;6210.441060304594;6239.126522286792;6251.156093927261;6270.717817698123;5035.395752509722;5884;5932.347441781487;5938.287405511193;6030;5284.154535274338;5956.702164891824;6012.609180403259;6032.788852390146;6094.994325936171;5854.034084376201;5860.765569377348;5862.384449632727;5865.882682346414;5876.529877609695;5831.183634291117;5973.910391344047;5980.401959804159;6013.9097913532205;6055.2430946131735;5744.798932037525;5786.987277226384;5821.825345239649;5846.093855452412;5853.414658534112 20:24:09 INFO - PROCESS | 1952 | |1;ext.html;449.9370088187654;479.4653960833671;518.5229588778324;520.1807153732872;532.2867357740627;30075.849620751895;30967.690323096762;38803.22393552127;40111.99720006994;42071.78964105183;41501.16997660045;41867.790661046725;41871.58128418715;41907.37138942918;42016.78991605045;9318.767030824216;9495.762605934873;9501.429914205135;9501.619935202583;9504.334696571243;9339.859902101474;9441.527923603826;9448.196903263231;9450.85823712645;9451.054894510562;9393.57728902201;9424.764380890498;9441.716748497556;9446.527673616292;9458.38520496169;9357.204637605777;9417.858732119024;9444.338896277264;9444.716658500187;9447.196988256006;9304.023077576892;9400.905990940071;9402.858957115648;9413.717588472357;9419.152276295166;32844;37520.061998449986;39158;39413.80293098516;39555.80222098871;3341.63242043375;3351.2962277921665;3432.1248081739245;3439.6560343965652;3790.7156963227867;29492;29866.850665746748;30133;30262.394752105054;30639;3155.3846999834814;3182.299894023311;3196.2329041030216;3276.770626056177;3288.8848890288964;475495;476169;476214.9999999965;476697;478694;47551.52448475471;53846.730766345914;54081.72959135258;54155.45844541504;54227.45772542303;33156.3368732624;33877;33938.83030584881;35222;35226.119347016014;15702.607434814221;15714.371425142866;15903.840961590236;15923.840761592235;15924.92037539805;12099;12184.20802647825;12187.32969686658;12187.32969686658;12216.816747748791;18406.81593184051;18416.079196040144;18426.355077572487;18432;18471.53821154455;7766.495177813424;7891.565963872042;7915.683372665029;7959.442839001214;7975.481593696507;730.4704089535151;917.5641570254076;930.4510338900081;1011.8178727829062;1023.3450591621369;27703.722962770516;29298.853505732335;29368.853155734083;29992.95024674126;30047;1006.4917216805467;1018.8318927377048;1028.1106842581114;1034.022848408262;1064.5901327988624;12268;12629;13031.811812408747;15316.770248446282;15645.608859778597;612148;635231;640134;642137;642765;55328.44671553233;55814;55844.44155558474;55883.72058139765;55886.72056639691;1337.6588969812635;1359.4698067753584;1378.6622277542067;1380.4478208716594;1381.8341798983986;27843;28568.142955711362;28989.501965272408;29266;29522.704772951995;47350.76324618493;47536;47784.761076194394;47835.28247076366;48053.27920081132;503379.4831025968;506358;506519;507262;507587;24855.50288994246;25088.247352579452;25126.62310065387;25130.74869251284;25142;45038.77480612576;45082.774586126856;45512.772436138934;45786.276715410575;46051.76974115108;37642.811785940896;37773.81113094417;38298.0425489365;39252;39503.80248098741;32207.838960805046;34285.828570857986;37541.81229093837;37836.05409864776;39055.2188956225;31648.367032658756;32403.865864695566;37126;37672.811635941645;37675.62324376721;27552.586711198946;34224.19265353449;34376;34625;35099.649003509636;23815.04739810388;24691.62885775597;24932.501349972536;24936.37659058538;25323.873380633715 20:24:09 INFO - PROCESS | 1952 | |2;jquery.html;347.1737265308565;364.79753736676145;369.91122130688626;375.72759749181836;395.8337498250736;14651.926740366296;14860.702785944275;15752.684946301082;20078;20480.487987800312;19145;19965.20139194434;20108.798912010876;20303.796962030374;20355.389338319837;5711.685857277848;5998.940010599882;6003.219581454405;6003.8198854034445;6018.127371531125;4493.910121797562;5903.704814759245;5973.581849270553;5973.731182096794;5986.730597123118;5565.387807341189;5781.479666829984;5917.88164236715;5926.733297001645;5952.375000624937;5680.772769089232;5782.277215348082;5786.334571524287;5787.681677507735;5817.796377126801;5072.315237442935;5074;5083.822066227664;5086.364204474441;5356.464353564651;12581.622551323475;12781.936090319488;12836.871631283755;12846.935765321114;17949.7307540387;2133.2107120365563;2151.7310336207975;2283.7259528856475;2285.4743409015855;2295.483516208856;9147.77130571741;9348.813023739554;9541.713748587554;9756.658516951873;10057.748556286151;1769.5399196209078;1797.6494583556212;1817.3730063128332;1826.1599664154505;1837.73352863834;75063;105173.4741326296;114945;124200.75799241982;124536;7794.766157015299;9554.378965367228;9809.860844529681;9929.330896809295;10097.747556311004;12244.755104897853;12279;12296.815547766791;12309.753804923852;12309.876901231051;7579.886301705479;7814.858125193657;7822.530648161015;7969.083555391088;7983.401244906655;8176.059753128347;8188.631511581874;8192.467489613156;8284.958575207085;8361.665533378597;3793.146542028077;3801.8669346572738;3818.9427158592644;3866.420036994417;3924.293627147141;1848.9167987440596;1906.4661894669514;1906.7044608085769;1910.646530391882;1916.626257879728;748.7903387051634;790.3598085550723;837.4012581004489;903.6927444668844;907.682311191091;20729.378118656463;21199.364019079454;21523.816190109694;21528.81591512482;21706.023228954735;748.2031636307315;852.6461518469889;886.6409104312763;909.4043401571981;917.1195652173988;8453.492790432594;10443.94778026105;10888.727781805519;11211.55153793839;11251.774964500664;576689;588715;590757;591830.040849793;593578.0321098453;92835.5358223218;103786;124452.90675302227;126928;133529;949.3449519831295;997.4314640654902;998.5207100591757;1002.0931057392925;1008.7276435362411;14678.559643210721;19498.31755888537;19499.8050019498;19564.510887227363;19583.510412239237;44262.89342766457;53353;54108;55178;56485.15272270837;79321;79540;81067.18932810596;81255;81827.18172818434;6338.524610654127;7891.802704932423;7906.802329941797;7982;8148.83702325962;10420.583176672848;12326.198797078161;12568.874311257136;12632.05259605515;12647.557335493213;10496;10857.239993200401;10914.399708016135;11104.944475277573;11118.38848863288;9235.76910577241;10254.743631409274;10646.73383165427;10690.89309106899;11141.275817071864;10049.045340692623;10335.328203666737;10934.234603577674;11059.115270778157;11108.500117494894;7080.929190708027;7605.847883042419;7840.686372545034;7897.960510197412;8073.273405393543;9595.42427454355;9778.462184579916;10894.346339219672;11068.833967490333;11236.55053797839 20:24:09 INFO - PROCESS | 1952 | |3;mootools.html;1350.689341451466;1481.7925490431342;1622.594351412147;1799.6400719856033;1827.2234300422326;7948.52308861468;8352.039515455725;8356.331493480515;8368.372372072095;8390.119037501066;7376.373008294301;7670.463067585271;7978.521288722666;8197.303229225508;8220.630071646776;2305.87317697527;2338.3452633262627;2338.8830558472096;2339.5203983183405;2339.7192336919593;2060.546679730457;2323.3146221864504;2361.811055115595;2374.893129809163;2377.263048454976;2074.284371891695;2099.4856260216193;2104.852660313779;2106.6524023536176;2106.9894650526726;2243.5737209930144;2320.965185522227;2325.7906788389128;2327.7672232776586;2333.3233362325077;2311.791938725523;2337.439014636492;2339.415146213447;2341.1103780563276;2350.6944097267415;8263.049749278789;8296.709615163501;8375.20435558621;8383.203595658402;8396.286315663176;5017.172166592508;5178.585713142939;5182.4299327073995;5183.2743415921805;5185.403678576974;3338.8664453421834;3502.439609662442;3539.752217344787;3577.195131095483;3606.711463082947;1011.3830563356347;1051.784384201237;1081.1566977757361;1095.956161753529;1111.9666410007712;10564.947175264151;10709.25035247533;10733.62432314873;10752.301100428447;10776;4294.248506511348;4488.035072459437;4562.954370456319;4566.292224705176;4650.372199753057;9186.678466253783;9296.349255552046;9302.581328854547;9302.86045709315;9307.3019523536;5163.199704045879;5165.173572228426;5166.225066240019;5168.121419358718;5171.741412929414;4602.585767280961;4731.574158325767;4732.148213321635;4742.596879265301;4749.64377671676;3671.210689701727;3681.5582130144016;3750.5686846012513;3812.046988252937;3844.6347596978244;1064.6007747094839;1240.3984067727145;1242.0746543824907;1256.7926292161874;1266.4870727355435;742.1502379775127;829.1625458287174;882.7969566999626;886.5212785095976;945.9877930614176;5305.840824775263;5572;5651.943480565224;5864.736086876015;5908.704564771744;810.7000409848393;922.0065800900113;988.8961659025842;996.8604395384652;1042.854000439939;3520.15516276097;3680.963190368062;3743.494628225155;3773.4528493368307;3885.533735951704;9637.71086867395;10083.798324033625;10091.293609447415;10126.898731012596;10275.7431064224;9365.344425890258;10021.599136034623;10028.997100289913;10041.548130334153;10042.146417554444;7490.737824176236;7742.4193185510185;7794.532328060334;7810.921890781133;7832.765017049498;5364.4099149094345;5459.062032978394;5536.390996990408;5573.888522229451;5613.550915926798;8833.469991800512;8943.508107054175;9211.539423028957;9246.815063698823;9368.812623747623;9286.34995550305;9311.76720581991;9392.389494682824;9438.38650487716;9488.95255523718;7394.186639469762;7673.271039251262;7811.140774514913;7816.7264145754625;7825.334846538108;7832.530048197126;7919.485233459806;8006.399520036137;8014.479058861155;8017.799555011172;5588.804391846266;6934.341237582422;6990.2660220678135;7028.4728645352825;7161.4628902831455;6793.626350550767;6826.897596536155;6903.792886213421;7018.298170182941;7061.399781018671;6595.406413422815;6650.600963942179;6656.700448479927;6729.966350168414;6730.788458077593;3898.200868821828;4136.544980052132;4627.236505976476;4783.330333753347;4935.407751069894;4357.368181613585;4468.151051300242;4484.529124441887;4498.145352383037;4619.452483418093 20:24:09 INFO - PROCESS | 1952 | |4;prototype.html;272.97905832187615;296.41606036108857;305.854719008471;308.71443914379194;312.5109204095589;4584.7478388688605;5218.243354713564;5299.8410047698635;5310.230016647583;5320.813771517997;4908.803647854082;5016.419827754255;5067.366579177603;5131.743412829363;5254.737263136847;4025.7383270087353;4028.1540876415843;4063.3295506241584;4066.0444795473113;4071.9185616287655;4034.7377420467724;4041.4139949707273;4045.1909618076356;4048;4063.87808365748;3717.758345707534;3926.3325234710023;3970.5235371755284;3978.4231286463446;3982.9843389935527;3530.064532898784;3830.693544516432;3924.82338294776;3955.1694144229746;3969.8809035728973;3032.4844776388068;3202.9359412811846;3390.94847617193;3400.880969166067;3409.369266685671;4629.907401851978;4634.21218392874;4643.837465688718;4879.0239952819165;5087.389513258433;4305.784710764449;4456.465224173087;4534.4582841833935;4536.160810250114;4595.885102872421;5179.326687530597;5475.972620136914;5563.777448902039;5606.271184745998;5623.831285061455;3330.833458327098;3787.6780473659774;3796.4874741909766;3802.9429558556644;3816.084139806454;4907.288443175719;5388.299521062276;5647.265855438808;5821.505172060338;5873.882522349529;3443.345764304774;3584.211473475831;3779.7921114338424;3788.3938569828697;3821.3568165688466;3334.3164651246434;3889.0943437715882;3897.2595206910596;3918.294706952719;3919.1573811630637;3690.8892733218045;3941.231459865299;4257.936130958038;4435.800388982503;4497.437820272466;5559.388467268597;5567.331920169606;5582.581306401955;5583.3858275589655;5601.451339640454;1068.8343306787306;1284.460526578839;1307.1830106183636;1325.6213537920648;1333.7465881482487;836.9916300837035;846.7375113714768;852.4586887326592;895.4940458640847;903.6656437118309;5024;5162.45794191612;5210.817621383309;5251.553617942442;5295.947040529622;4896.4124305083615;5559.192607733069;5641.26663533738;5666.263385759825;5727.503909431077;4358.389825424443;4403.965068209032;4671.43942726875;5286.788528458896;5312.229726689686;2524.911628093008;2594.0272397850804;2783.8747256373513;2977.389635124794;2995.495270533481;4609.562091601292;5706.9714651426475;5736.942630573724;5755.510781583528;5801.622894511843;5816.975172910148;5838.211841401441;5845.240118784531;5849.298084229835;5851.736671849777;4810.478150538553;5771.903338365697;5788.80025590273;5800.709964501758;5867.354590994987;4533.320001999727;5401.135818269058;5611.410801865909;5638.688137863456;5662.357916204254;4818.445878723852;5310.814121505883;5614.438556144353;5653.145161693318;5665.546756259571;5044.117279476003;5407.648502847303;5740.540756739368;5768.307803063659;5794.82615521535;3497.667721566447;3616.3129005488872;3758.924821503609;3797.886063418102;3836.4437156612694;4941.899692630545;4960.172701560076;5578.748956296895;5627.606067575231;5658.717064146858;5322.323468107534;5513.979913715975;5544.75048622821;5547.55619550443;5598.30511862169;4893.412790465167;4925.359703238556;5489.1217405214975;5504;5518.420565840529;5122;5326.0945639240645;5335.706536140394;5408.405075441619;5427.185922111715;4787.688800227974;5224.190250511177;5487.972560137174;5493.533049690661;5497.560195184456;4744.857654270378;5341.092014357491;5351.708699150073;5424.105022671215;5520.611114164169 20:24:09 INFO - PROCESS | 1952 | |5;yui.html;699.3565919354194;718.4611541343992;757.303280981497;790.8892755014302;792.5957761541612;14646.780298295535;14787.556373308791;14804.25978701066;14819.110853348806;14844.92577537112;13117.54088606899;13226.338683065856;13230.404631791569;13272.20366777994;13279.402426890789;648.8021153548177;670.3999920071533;674.5244602555186;806.4999700185894;827.1563005734148;822.4736168851939;836.1930736838949;837.7696133563257;839.014158363923;839.3998291221775;890.1587999340626;890.6660002499062;892.1702816380763;893.2496702769672;894.0924961164424;689.7137687859532;712.1525384792133;851.1828644501286;889.1863944490783;889.5552223888088;645.5674697952384;649.8375406148463;650.1645385679379;650.7527139686936;651.775137577535;13800.447982080705;13900.652483687989;13931.930340348335;13935.442582296697;13944.721105577832;12796.424160912688;12866.035047371484;12919.289439080847;13035.348232588427;13054.151480153854;11264.43677816106;11904.880951190551;11954.462049207807;12127.696807579792;12194;4342.5874541918465;4376.584224498668;4438.112377524508;4464.241079016575;4579.3131030345385;86422.27155456919;86439.13560864374;87086.5645671774;87606;88549;10267.845982310271;10298.227632927561;10314.27800054004;10371.740706482398;10734.355938643706;18972.60820955129;19300.71048934267;19348.903255483634;19481;19522.511937201685;6464.676766161673;7477.102747670315;7516.624168791539;7690.077190737147;7758.262965018314;4125.319322311785;4177.91644167121;4188.267053265667;4236.406903033578;4312.676549258818;3158.873645054218;3219.146926064595;3248.2204270974794;3253.2192273854102;3265.689759472846;602.5902386377305;606.1866698362722;609.7286707415246;628.2743431336813;635.7697854651265;567.4013915319377;576.6367188671194;628.4281304013326;653.3074940563018;656.2682608891052;8629.61166747498;8939.329550283755;8986.86519702205;9120;9164.495952722664;521.559282406366;650.0218661789876;653.7711800869657;662.0995446193174;669.5480550628306;4222.451081359403;4395.846145384896;4417.425734654475;4468.910621787546;4524.411826462539;108076.4596177014;108631;109553.45223273833;110203;111300;84603;87587;91569;93026;94698.52650736702;76517;82436.58781706174;83210.75183872126;84152.15847841444;85305;8493.532855692996;8507.914920850711;8529.616167272612;8671.913280867111;8721.738347849574;42706;50331.74834125806;50570;50986.23520647119;55294.72352638211;67516;70234;70771.58456830731;72302.63848680723;77729.61135194288;12295.262284262973;12370.44333004999;12502.499900003899;12799.744005120032;12824.551140710404;13910.51313204033;14220.786688199478;14560;14659.340329685403;14739.336729847397;11988.340641264815;12683.492660293854;12736.68158296013;12788.616341509769;12806.743865122831;12972.545960891324;13233.801492977806;13458.932705336802;13481.797773033217;13665.316734163052;12440.937795310965;12479.31363775001;12792.360381980678;13016.804747928978;13136.868631313564;10714.57141714354;10897.891021090005;10973.561057557608;11047.116230701686;11126.944365278121;9562.808743825224;9640.373375730554;10394.58421663125;11038.061764749766;11135 20:24:09 INFO - PROCESS | 1952 | __end_tp_report 20:24:09 INFO - PROCESS | 1952 | __start_cc_report 20:24:09 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1771 20:24:09 INFO - PROCESS | 1952 | __end_cc_report 20:24:09 INFO - PROCESS | 1952 | __startTimestamp1478143449870__endTimestamp 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | ------- Summary: start ------- 20:24:09 INFO - PROCESS | 1952 | Number of tests: 6 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5680.90 Median:5831.18 stddev:650.83 (11.2%) stddev-sans-first:617.80 20:24:09 INFO - PROCESS | 1952 | Values: 2995.9 3303.4 3303.7 3334.2 3357.0 5506.7 5615.9 5621.6 5690.8 5698.9 5617.6 5653.6 5654.6 5671.2 5673.9 5118.1 5155.0 5161.1 5162.2 5195.6 5242.5 5243.5 5251.0 5260.8 5263.8 5124.2 5173.4 5193.1 5230.7 5252.7 5227.5 5235.2 5243.5 5272.3 5304.7 4991.1 5114.5 5152.1 5171.6 5181.5 5491.0 5538.8 5549.6 5591.2 5607.1 6166.4 6202.3 6225.5 6232.6 6259.4 5865.9 6137.1 6198.5 6257.4 6291.3 5690.4 5709.9 5738.7 5759.2 5767.8 6200.2 6241.2 6251.7 6253.7 6254.6 5704.2 6106.9 6136.0 6142.6 6148.4 5169.5 6095.4 6111.6 6137.7 6146.4 6147.3 6171.0 6176.1 6180.9 6186.1 6142.4 6204.1 6227.8 6231.4 6232.6 4815.0 4844.8 4899.3 4924.9 4951.7 3743.2 4399.1 4414.2 4445.7 4470.7 5743.4 5746.2 5753.7 5770.6 5812.2 6217.2 6265.3 6280.2 6288.2 6288.2 5599.0 5639.5 5657.9 5707.0 5707.8 5322.7 5334.5 5357.5 5373.9 5427.6 6298.6 6322.5 6326.6 6339.5 6340.0 6138.6 6147.5 6261.9 6281.7 6283.3 6198.1 6234.9 6257.7 6264.3 6288.7 6106.3 6127.2 6137.9 6145.8 6148.2 6196.9 6210.4 6239.1 6251.2 6270.7 5035.4 5884.0 5932.3 5938.3 6030.0 5284.2 5956.7 6012.6 6032.8 6095.0 5854.0 5860.8 5862.4 5865.9 5876.5 5831.2 5973.9 5980.4 6013.9 6055.2 5744.8 5787.0 5821.8 5846.1 5853.4 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:66006.75 Median:20813.38 stddev:145291.50 (698.1%) stddev-sans-first:145615.71 20:24:09 INFO - PROCESS | 1952 | Values: 449.9 479.5 518.5 520.2 532.3 30075.8 30967.7 38803.2 40112.0 42071.8 41501.2 41867.8 41871.6 41907.4 42016.8 9318.8 9495.8 9501.4 9501.6 9504.3 9339.9 9441.5 9448.2 9450.9 9451.1 9393.6 9424.8 9441.7 9446.5 9458.4 9357.2 9417.9 9444.3 9444.7 9447.2 9304.0 9400.9 9402.9 9413.7 9419.2 32844.0 37520.1 39158.0 39413.8 39555.8 3341.6 3351.3 3432.1 3439.7 3790.7 29492.0 29866.9 30133.0 30262.4 30639.0 3155.4 3182.3 3196.2 3276.8 3288.9 475495.0 476169.0 476215.0 476697.0 478694.0 47551.5 53846.7 54081.7 54155.5 54227.5 33156.3 33877.0 33938.8 35222.0 35226.1 15702.6 15714.4 15903.8 15923.8 15924.9 12099.0 12184.2 12187.3 12187.3 12216.8 18406.8 18416.1 18426.4 18432.0 18471.5 7766.5 7891.6 7915.7 7959.4 7975.5 730.5 917.6 930.5 1011.8 1023.3 27703.7 29298.9 29368.9 29993.0 30047.0 1006.5 1018.8 1028.1 1034.0 1064.6 12268.0 12629.0 13031.8 15316.8 15645.6 612148.0 635231.0 640134.0 642137.0 642765.0 55328.4 55814.0 55844.4 55883.7 55886.7 1337.7 1359.5 1378.7 1380.4 1381.8 27843.0 28568.1 28989.5 29266.0 29522.7 47350.8 47536.0 47784.8 47835.3 48053.3 503379.5 506358.0 506519.0 507262.0 507587.0 24855.5 25088.2 25126.6 25130.7 25142.0 45038.8 45082.8 45512.8 45786.3 46051.8 37642.8 37773.8 38298.0 39252.0 39503.8 32207.8 34285.8 37541.8 37836.1 39055.2 31648.4 32403.9 37126.0 37672.8 37675.6 27552.6 34224.2 34376.0 34625.0 35099.6 23815.0 24691.6 24932.5 24936.4 25323.9 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:33514.19 Median:3895.36 stddev:98042.12 (2516.9%) stddev-sans-first:98285.52 20:24:09 INFO - PROCESS | 1952 | Values: 347.2 364.8 369.9 375.7 395.8 14651.9 14860.7 15752.7 20078.0 20480.5 19145.0 19965.2 20108.8 20303.8 20355.4 5711.7 5998.9 6003.2 6003.8 6018.1 4493.9 5903.7 5973.6 5973.7 5986.7 5565.4 5781.5 5917.9 5926.7 5952.4 5680.8 5782.3 5786.3 5787.7 5817.8 5072.3 5074.0 5083.8 5086.4 5356.5 12581.6 12781.9 12836.9 12846.9 17949.7 2133.2 2151.7 2283.7 2285.5 2295.5 9147.8 9348.8 9541.7 9756.7 10057.7 1769.5 1797.6 1817.4 1826.2 1837.7 75063.0 105173.5 114945.0 124200.8 124536.0 7794.8 9554.4 9809.9 9929.3 10097.7 12244.8 12279.0 12296.8 12309.8 12309.9 7579.9 7814.9 7822.5 7969.1 7983.4 8176.1 8188.6 8192.5 8285.0 8361.7 3793.1 3801.9 3818.9 3866.4 3924.3 1848.9 1906.5 1906.7 1910.6 1916.6 748.8 790.4 837.4 903.7 907.7 20729.4 21199.4 21523.8 21528.8 21706.0 748.2 852.6 886.6 909.4 917.1 8453.5 10443.9 10888.7 11211.6 11251.8 576689.0 588715.0 590757.0 591830.0 593578.0 92835.5 103786.0 124452.9 126928.0 133529.0 949.3 997.4 998.5 1002.1 1008.7 14678.6 19498.3 19499.8 19564.5 19583.5 44262.9 53353.0 54108.0 55178.0 56485.2 79321.0 79540.0 81067.2 81255.0 81827.2 6338.5 7891.8 7906.8 7982.0 8148.8 10420.6 12326.2 12568.9 12632.1 12647.6 10496.0 10857.2 10914.4 11104.9 11118.4 9235.8 10254.7 10646.7 10690.9 11141.3 10049.0 10335.3 10934.2 11059.1 11108.5 7080.9 7605.8 7840.7 7898.0 8073.3 9595.4 9778.5 10894.3 11068.8 11236.6 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5400.68 Median:5164.19 stddev:2961.94 (57.4%) stddev-sans-first:2954.61 20:24:09 INFO - PROCESS | 1952 | Values: 1350.7 1481.8 1622.6 1799.6 1827.2 7948.5 8352.0 8356.3 8368.4 8390.1 7376.4 7670.5 7978.5 8197.3 8220.6 2305.9 2338.3 2338.9 2339.5 2339.7 2060.5 2323.3 2361.8 2374.9 2377.3 2074.3 2099.5 2104.9 2106.7 2107.0 2243.6 2321.0 2325.8 2327.8 2333.3 2311.8 2337.4 2339.4 2341.1 2350.7 8263.0 8296.7 8375.2 8383.2 8396.3 5017.2 5178.6 5182.4 5183.3 5185.4 3338.9 3502.4 3539.8 3577.2 3606.7 1011.4 1051.8 1081.2 1096.0 1112.0 10564.9 10709.3 10733.6 10752.3 10776.0 4294.2 4488.0 4563.0 4566.3 4650.4 9186.7 9296.3 9302.6 9302.9 9307.3 5163.2 5165.2 5166.2 5168.1 5171.7 4602.6 4731.6 4732.1 4742.6 4749.6 3671.2 3681.6 3750.6 3812.0 3844.6 1064.6 1240.4 1242.1 1256.8 1266.5 742.2 829.2 882.8 886.5 946.0 5305.8 5572.0 5651.9 5864.7 5908.7 810.7 922.0 988.9 996.9 1042.9 3520.2 3681.0 3743.5 3773.5 3885.5 9637.7 10083.8 10091.3 10126.9 10275.7 9365.3 10021.6 10029.0 10041.5 10042.1 7490.7 7742.4 7794.5 7810.9 7832.8 5364.4 5459.1 5536.4 5573.9 5613.6 8833.5 8943.5 9211.5 9246.8 9368.8 9286.3 9311.8 9392.4 9438.4 9489.0 7394.2 7673.3 7811.1 7816.7 7825.3 7832.5 7919.5 8006.4 8014.5 8017.8 5588.8 6934.3 6990.3 7028.5 7161.5 6793.6 6826.9 6903.8 7018.3 7061.4 6595.4 6650.6 6656.7 6730.0 6730.8 3898.2 4136.5 4627.2 4783.3 4935.4 4357.4 4468.2 4484.5 4498.1 4619.5 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4452.65 Median:4988.30 stddev:1378.25 (27.6%) stddev-sans-first:1345.94 20:24:09 INFO - PROCESS | 1952 | Values: 273.0 296.4 305.9 308.7 312.5 4584.7 5218.2 5299.8 5310.2 5320.8 4908.8 5016.4 5067.4 5131.7 5254.7 4025.7 4028.2 4063.3 4066.0 4071.9 4034.7 4041.4 4045.2 4048.0 4063.9 3717.8 3926.3 3970.5 3978.4 3983.0 3530.1 3830.7 3924.8 3955.2 3969.9 3032.5 3202.9 3390.9 3400.9 3409.4 4629.9 4634.2 4643.8 4879.0 5087.4 4305.8 4456.5 4534.5 4536.2 4595.9 5179.3 5476.0 5563.8 5606.3 5623.8 3330.8 3787.7 3796.5 3802.9 3816.1 4907.3 5388.3 5647.3 5821.5 5873.9 3443.3 3584.2 3779.8 3788.4 3821.4 3334.3 3889.1 3897.3 3918.3 3919.2 3690.9 3941.2 4257.9 4435.8 4497.4 5559.4 5567.3 5582.6 5583.4 5601.5 1068.8 1284.5 1307.2 1325.6 1333.7 837.0 846.7 852.5 895.5 903.7 5024.0 5162.5 5210.8 5251.6 5295.9 4896.4 5559.2 5641.3 5666.3 5727.5 4358.4 4404.0 4671.4 5286.8 5312.2 2524.9 2594.0 2783.9 2977.4 2995.5 4609.6 5707.0 5736.9 5755.5 5801.6 5817.0 5838.2 5845.2 5849.3 5851.7 4810.5 5771.9 5788.8 5800.7 5867.4 4533.3 5401.1 5611.4 5638.7 5662.4 4818.4 5310.8 5614.4 5653.1 5665.5 5044.1 5407.6 5740.5 5768.3 5794.8 3497.7 3616.3 3758.9 3797.9 3836.4 4941.9 4960.2 5578.7 5627.6 5658.7 5322.3 5514.0 5544.8 5547.6 5598.3 4893.4 4925.4 5489.1 5504.0 5518.4 5122.0 5326.1 5335.7 5408.4 5427.2 4787.7 5224.2 5488.0 5493.5 5497.6 4744.9 5341.1 5351.7 5424.1 5520.6 20:24:09 INFO - PROCESS | 1952 | 20:24:09 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:20063.84 Median:4466.58 stddev:29185.02 (653.4%) stddev-sans-first:29230.67 20:24:09 INFO - PROCESS | 1952 | Values: 699.4 718.5 757.3 790.9 792.6 14646.8 14787.6 14804.3 14819.1 14844.9 13117.5 13226.3 13230.4 13272.2 13279.4 648.8 670.4 674.5 806.5 827.2 822.5 836.2 837.8 839.0 839.4 890.2 890.7 892.2 893.2 894.1 689.7 712.2 851.2 889.2 889.6 645.6 649.8 650.2 650.8 651.8 13800.4 13900.7 13931.9 13935.4 13944.7 12796.4 12866.0 12919.3 13035.3 13054.2 11264.4 11904.9 11954.5 12127.7 12194.0 4342.6 4376.6 4438.1 4464.2 4579.3 86422.3 86439.1 87086.6 87606.0 88549.0 10267.8 10298.2 10314.3 10371.7 10734.4 18972.6 19300.7 19348.9 19481.0 19522.5 6464.7 7477.1 7516.6 7690.1 7758.3 4125.3 4177.9 4188.3 4236.4 4312.7 3158.9 3219.1 3248.2 3253.2 3265.7 602.6 606.2 609.7 628.3 635.8 567.4 576.6 628.4 653.3 656.3 8629.6 8939.3 8986.9 9120.0 9164.5 521.6 650.0 653.8 662.1 669.5 4222.5 4395.8 4417.4 4468.9 4524.4 108076.5 108631.0 109553.5 110203.0 111300.0 84603.0 87587.0 91569.0 93026.0 94698.5 76517.0 82436.6 83210.8 84152.2 85305.0 8493.5 8507.9 8529.6 8671.9 8721.7 42706.0 50331.7 50570.0 50986.2 55294.7 67516.0 70234.0 70771.6 72302.6 77729.6 12295.3 12370.4 12502.5 12799.7 12824.6 13910.5 14220.8 14560.0 14659.3 14739.3 11988.3 12683.5 12736.7 12788.6 12806.7 12972.5 13233.8 13458.9 13481.8 13665.3 12440.9 12479.3 12792.4 13016.8 13136.9 10714.6 10897.9 10973.6 11047.1 11126.9 9562.8 9640.4 10394.6 11038.1 11135.0 20:24:09 INFO - PROCESS | 1952 | -------- Summary: end -------- 20:24:09 INFO - PROCESS | 1952 | 20:24:10 INFO - TEST-INFO | 1952: exit 0 20:24:10 INFO - TEST-OK | dromaeo_css | took 1120635ms 20:24:10 INFO - TEST-START | kraken 20:24:10 INFO - operating with platform_type : mac_ 20:24:10 INFO - Initialising browser for kraken test... 20:24:10 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpMF7HL3/profile http://localhost:49433/getInfo.html) 20:24:13 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 20:24:13 INFO - PROCESS | 1954 | colorDepth:24 20:24:13 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 20:24:13 INFO - PROCESS | 1954 | __metrics 20:24:13 INFO - PROCESS | 1954 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:24:13 INFO - PROCESS | 1954 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:24:13 INFO - TEST-INFO | 1954: exit 0 20:24:13 INFO - Browser initialized. 20:24:13 INFO - Running cycle 1/1 for kraken test... 20:24:13 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpMF7HL3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:25 INFO - PROCESS | 1955 | RSS: Main: 239460352 20:24:25 INFO - PROCESS | 1955 | 20:24:28 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49433/tests/kraken/driver.html?testName=audio-beat-detection) 20:24:29 INFO - PROCESS | 1955 | RSS: Main: 291557376 20:24:29 INFO - PROCESS | 1955 | 20:24:32 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49433/tests/kraken/driver.html?testName=audio-dft) 20:24:32 INFO - PROCESS | 1955 | RSS: Main: 291454976 20:24:32 INFO - PROCESS | 1955 | 20:24:36 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49433/tests/kraken/driver.html?testName=audio-fft) 20:24:36 INFO - PROCESS | 1955 | RSS: Main: 383799296 20:24:36 INFO - PROCESS | 1955 | 20:24:38 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49433/tests/kraken/driver.html?testName=audio-oscillator) 20:24:39 INFO - PROCESS | 1955 | RSS: Main: 284561408 20:24:39 INFO - PROCESS | 1955 | 20:24:41 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49433/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:24:42 INFO - PROCESS | 1955 | RSS: Main: 288751616 20:24:42 INFO - PROCESS | 1955 | 20:24:44 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49433/tests/kraken/driver.html?testName=imaging-darkroom) 20:24:45 INFO - PROCESS | 1955 | RSS: Main: 326893568 20:24:45 INFO - PROCESS | 1955 | 20:24:48 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49433/tests/kraken/driver.html?testName=imaging-desaturate) 20:24:49 INFO - PROCESS | 1955 | RSS: Main: 327823360 20:24:49 INFO - PROCESS | 1955 | 20:24:51 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49433/tests/kraken/driver.html?testName=json-parse-financial) 20:24:52 INFO - PROCESS | 1955 | RSS: Main: 325861376 20:24:52 INFO - PROCESS | 1955 | 20:24:54 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49433/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:24:54 INFO - PROCESS | 1955 | RSS: Main: 303603712 20:24:54 INFO - PROCESS | 1955 | 20:24:56 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:24:57 INFO - PROCESS | 1955 | RSS: Main: 307200000 20:24:57 INFO - PROCESS | 1955 | 20:24:59 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:25:00 INFO - PROCESS | 1955 | RSS: Main: 309239808 20:25:00 INFO - PROCESS | 1955 | 20:25:03 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:25:03 INFO - PROCESS | 1955 | RSS: Main: 317083648 20:25:03 INFO - PROCESS | 1955 | 20:25:07 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:25:07 INFO - PROCESS | 1955 | RSS: Main: 317493248 20:25:07 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:49433/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49433/tests/kraken/driver.html?testName=ai-astar) 20:25:09 INFO - PROCESS | 1955 | RSS: Main: 360521728 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | __start_tp_report 20:25:09 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 20:25:09 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 20:25:09 INFO - PROCESS | 1955 | |i|pagename|runs| 20:25:09 INFO - PROCESS | 1955 | |0;ai-astar;113;114;114;114;114;113;113;113;112;114 20:25:09 INFO - PROCESS | 1955 | |1;audio-beat-detection;150;112;112;150;114;113;110;110;144;114 20:25:09 INFO - PROCESS | 1955 | |2;audio-dft;185;186;188;184;184;183;181;181;183;184 20:25:09 INFO - PROCESS | 1955 | |3;audio-fft;80;58;60;88;63;60;58;56;83;57 20:25:09 INFO - PROCESS | 1955 | |4;audio-oscillator;88;69;90;70;68;69;88;68;68;92 20:25:09 INFO - PROCESS | 1955 | |5;imaging-gaussian-blur;105;92;133;129;112;86;90;87;91;87 20:25:09 INFO - PROCESS | 1955 | |6;imaging-darkroom;97;97;99;102;98;98;97;98;97;98 20:25:09 INFO - PROCESS | 1955 | |7;imaging-desaturate;84;85;87;119;80;83;99;122;84;82 20:25:09 INFO - PROCESS | 1955 | |8;json-parse-financial;56;55;55;56;55;56;56;56;56;56 20:25:09 INFO - PROCESS | 1955 | |9;json-stringify-tinderbox;74;78;79;75;73;71;70;71;70;72 20:25:09 INFO - PROCESS | 1955 | |10;stanford-crypto-aes;90;85;85;86;85;89;89;89;86;85 20:25:09 INFO - PROCESS | 1955 | |11;stanford-crypto-ccm;146;164;156;142;156;156;138;155;219;185 20:25:09 INFO - PROCESS | 1955 | |12;stanford-crypto-pbkdf2;225;195;182;238;180;175;176;174;173;173 20:25:09 INFO - PROCESS | 1955 | |13;stanford-crypto-sha256-iterative;68;68;68;66;73;86;89;88;75;65 20:25:09 INFO - PROCESS | 1955 | __end_tp_report 20:25:09 INFO - PROCESS | 1955 | __start_cc_report 20:25:09 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1977 20:25:09 INFO - PROCESS | 1955 | __end_cc_report 20:25:09 INFO - PROCESS | 1955 | __startTimestamp1478143509704__endTimestamp 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | ------- Summary: start ------- 20:25:09 INFO - PROCESS | 1955 | Number of tests: 14 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 20:25:09 INFO - PROCESS | 1955 | Values: 113.0 114.0 114.0 114.0 114.0 113.0 113.0 113.0 112.0 114.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:114.00 stddev:17.45 (15.3%) stddev-sans-first:15.51 20:25:09 INFO - PROCESS | 1955 | Values: 150.0 112.0 112.0 150.0 114.0 113.0 110.0 110.0 144.0 114.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:2.13 (1.2%) stddev-sans-first:2.22 20:25:09 INFO - PROCESS | 1955 | Values: 185.0 186.0 188.0 184.0 184.0 183.0 181.0 181.0 183.0 184.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.50 stddev:12.28 (20.0%) stddev-sans-first:11.99 20:25:09 INFO - PROCESS | 1955 | Values: 80.0 58.0 60.0 88.0 63.0 60.0 58.0 56.0 83.0 57.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:79.00 stddev:10.83 (13.7%) stddev-sans-first:10.73 20:25:09 INFO - PROCESS | 1955 | Values: 88.0 69.0 90.0 70.0 68.0 69.0 88.0 68.0 68.0 92.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:87.00 stddev:17.84 (20.5%) stddev-sans-first:18.87 20:25:09 INFO - PROCESS | 1955 | Values: 105.0 92.0 133.0 129.0 112.0 86.0 90.0 87.0 91.0 87.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:98.00 stddev:1.52 (1.6%) stddev-sans-first:1.56 20:25:09 INFO - PROCESS | 1955 | Values: 97.0 97.0 99.0 102.0 98.0 98.0 97.0 98.0 97.0 98.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:84.00 stddev:15.64 (18.6%) stddev-sans-first:16.29 20:25:09 INFO - PROCESS | 1955 | Values: 84.0 85.0 87.0 119.0 80.0 83.0 99.0 122.0 84.0 82.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 20:25:09 INFO - PROCESS | 1955 | Values: 56.0 55.0 55.0 56.0 55.0 56.0 56.0 56.0 56.0 56.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.50 stddev:3.20 (4.4%) stddev-sans-first:3.38 20:25:09 INFO - PROCESS | 1955 | Values: 74.0 78.0 79.0 75.0 73.0 71.0 70.0 71.0 70.0 72.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.50 stddev:2.08 (2.4%) stddev-sans-first:1.88 20:25:09 INFO - PROCESS | 1955 | Values: 90.0 85.0 85.0 86.0 85.0 89.0 89.0 89.0 86.0 85.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:161.70 Median:156.00 stddev:23.97 (15.4%) stddev-sans-first:24.74 20:25:09 INFO - PROCESS | 1955 | Values: 146.0 164.0 156.0 142.0 156.0 156.0 138.0 155.0 219.0 185.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:181.00 stddev:23.49 (13.0%) stddev-sans-first:21.01 20:25:09 INFO - PROCESS | 1955 | Values: 225.0 195.0 182.0 238.0 180.0 175.0 176.0 174.0 173.0 173.0 20:25:09 INFO - PROCESS | 1955 | 20:25:09 INFO - PROCESS | 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:74.00 stddev:9.52 (12.9%) stddev-sans-first:9.80 20:25:09 INFO - PROCESS | 1955 | Values: 68.0 68.0 68.0 66.0 73.0 86.0 89.0 88.0 75.0 65.0 20:25:09 INFO - PROCESS | 1955 | -------- Summary: end -------- 20:25:09 INFO - PROCESS | 1955 | 20:25:10 INFO - TEST-INFO | 1955: exit 0 20:25:10 INFO - TEST-OK | kraken | took 60069ms 20:25:10 INFO - SUITE-END | took 1180s 20:25:10 INFO - Completed test suite (00:19:40) 20:25:10 INFO - javascript benchmark 20:25:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2995.8651860666273, 3303.4384154693707, 3303.7191838693693, 3334.216459132102, 3356.9664303356976, 5506.669599824013, 5615.943840561593, 5621.57838162138, 5690.772369105231, 5698.886022279557, 5617.578681598876, 5653.632513886594, 5654.632448890829, 5671.2060311556415, 5673.943260567393, 5118.129917913954, 5155.046316431453, 5161.122609156452, 5162.199859021857, 5195.636305458619, 5242.528172464477, 5243.528082472577, 5250.973745131269, 5260.842174734745, 5263.789448422059, 5124.180131179012, 5173.405058418273, 5193.091209038422, 5230.686158830483, 5252.65857719247, 5227.451117632631, 5235.18854577543, 5243.475652434726, 5272.340957380327, 5304.73476326186, 4991.101601711727, 5114.514121158484, 5152.149895267276, 5171.5862730981435, 5181.533661970403, 5490.956718223525, 5538.75075621598, 5549.639273447254, 5591.217229587861, 5607.074832652607, 6166.414190651881, 6202.286737025254, 6225.470834979033, 6232.594881332699, 6259.37406259375, 5865.85335366615, 6137.079438084278, 6198.535109866733, 6257.43683068526, 6291.276503202099, 5690.374058853523, 5709.885802283931, 5738.68437235956, 5759.222504961776, 5767.769289228384, 6200.241736507229, 6241.18864547615, 6251.749930002749, 6253.74985000604, 6254.624722516573, 5704.22992895962, 6106.908396374058, 6135.96932015346, 6142.6314421134875, 6148.385161483816, 5169.4572069932865, 6095.35998720128, 6111.57218994675, 6137.6654897398075, 6146.416090471398, 6147.323794382614, 6171.0, 6176.135341052166, 6180.907286390708, 6186.133941248228, 6142.385761423822, 6204.13142160098, 6227.750889964442, 6231.4391704745885, 6232.594881332699, 4814.9518504815205, 4844.781984810692, 4899.338589290472, 4924.901501969941, 4951.653384263067, 3743.21392507571, 4399.076193999296, 4414.161309351213, 4445.688801783844, 4470.687051906335, 5743.368229494752, 5746.166805813134, 5753.654780713171, 5770.6249093808765, 5812.244408226988, 6217.191765070603, 6265.310815810257, 6280.183576135073, 6288.151099601495, 6288.245410550671, 5599.0, 5639.548836093103, 5657.915131273035, 5706.9714651426475, 5707.828765137053, 5322.707251101149, 5334.51989320963, 5357.491038351351, 5373.919391209135, 5427.565794736412, 6298.559100862895, 6322.494200464043, 6326.588771729915, 6339.524535659752, 6339.96830015847, 6138.570300078952, 6147.538934580014, 6261.937380626136, 6281.748730050746, 6283.3402492737605, 6198.101275315144, 6234.937650623436, 6257.7184026718, 6264.342244064308, 6288.685565721604, 6106.339326077157, 6127.189528148541, 6137.880059131387, 6145.770845830941, 6148.200733904742, 6196.938030619636, 6210.441060304594, 6239.126522286792, 6251.156093927261, 6270.717817698123, 5035.395752509722, 5884.0, 5932.347441781487, 5938.287405511193, 6030.0, 5284.154535274338, 5956.702164891824, 6012.609180403259, 6032.788852390146, 6094.994325936171, 5854.034084376201, 5860.765569377348, 5862.384449632727, 5865.882682346414, 5876.529877609695, 5831.183634291117, 5973.910391344047, 5980.401959804159, 6013.9097913532205, 6055.2430946131735, 5744.798932037525, 5786.987277226384, 5821.825345239649, 5846.093855452412, 5853.414658534112], "value": 5638.0155290747625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.9370088187654, 479.4653960833671, 518.5229588778324, 520.1807153732872, 532.2867357740627, 30075.849620751895, 30967.690323096762, 38803.22393552127, 40111.99720006994, 42071.78964105183, 41501.16997660045, 41867.790661046725, 41871.58128418715, 41907.37138942918, 42016.78991605045, 9318.767030824216, 9495.762605934873, 9501.429914205135, 9501.619935202583, 9504.334696571243, 9339.859902101474, 9441.527923603826, 9448.196903263231, 9450.85823712645, 9451.054894510562, 9393.57728902201, 9424.764380890498, 9441.716748497556, 9446.527673616292, 9458.38520496169, 9357.204637605777, 9417.858732119024, 9444.338896277264, 9444.716658500187, 9447.196988256006, 9304.023077576892, 9400.905990940071, 9402.858957115648, 9413.717588472357, 9419.152276295166, 32844.0, 37520.061998449986, 39158.0, 39413.80293098516, 39555.80222098871, 3341.63242043375, 3351.2962277921665, 3432.1248081739245, 3439.6560343965652, 3790.7156963227867, 29492.0, 29866.850665746748, 30133.0, 30262.394752105054, 30639.0, 3155.3846999834814, 3182.299894023311, 3196.2329041030216, 3276.770626056177, 3288.8848890288964, 475495.0, 476169.0, 476214.9999999965, 476697.0, 478694.0, 47551.52448475471, 53846.730766345914, 54081.72959135258, 54155.45844541504, 54227.45772542303, 33156.3368732624, 33877.0, 33938.83030584881, 35222.0, 35226.119347016014, 15702.607434814221, 15714.371425142866, 15903.840961590236, 15923.840761592235, 15924.92037539805, 12099.0, 12184.20802647825, 12187.32969686658, 12187.32969686658, 12216.816747748791, 18406.81593184051, 18416.079196040144, 18426.355077572487, 18432.0, 18471.53821154455, 7766.495177813424, 7891.565963872042, 7915.683372665029, 7959.442839001214, 7975.481593696507, 730.4704089535151, 917.5641570254076, 930.4510338900081, 1011.8178727829062, 1023.3450591621369, 27703.722962770516, 29298.853505732335, 29368.853155734083, 29992.95024674126, 30047.0, 1006.4917216805467, 1018.8318927377048, 1028.1106842581114, 1034.022848408262, 1064.5901327988624, 12268.0, 12629.0, 13031.811812408747, 15316.770248446282, 15645.608859778597, 612148.0, 635231.0, 640134.0, 642137.0, 642765.0, 55328.44671553233, 55814.0, 55844.44155558474, 55883.72058139765, 55886.72056639691, 1337.6588969812635, 1359.4698067753584, 1378.6622277542067, 1380.4478208716594, 1381.8341798983986, 27843.0, 28568.142955711362, 28989.501965272408, 29266.0, 29522.704772951995, 47350.76324618493, 47536.0, 47784.761076194394, 47835.28247076366, 48053.27920081132, 503379.4831025968, 506358.0, 506519.0, 507262.0, 507587.0, 24855.50288994246, 25088.247352579452, 25126.62310065387, 25130.74869251284, 25142.0, 45038.77480612576, 45082.774586126856, 45512.772436138934, 45786.276715410575, 46051.76974115108, 37642.811785940896, 37773.81113094417, 38298.0425489365, 39252.0, 39503.80248098741, 32207.838960805046, 34285.828570857986, 37541.81229093837, 37836.05409864776, 39055.2188956225, 31648.367032658756, 32403.865864695566, 37126.0, 37672.811635941645, 37675.62324376721, 27552.586711198946, 34224.19265353449, 34376.0, 34625.0, 35099.649003509636, 23815.04739810388, 24691.62885775597, 24932.501349972536, 24936.37659058538, 25323.873380633715], "value": 18978.115941604636, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1737265308565, 364.79753736676145, 369.91122130688626, 375.72759749181836, 395.8337498250736, 14651.926740366296, 14860.702785944275, 15752.684946301082, 20078.0, 20480.487987800312, 19145.0, 19965.20139194434, 20108.798912010876, 20303.796962030374, 20355.389338319837, 5711.685857277848, 5998.940010599882, 6003.219581454405, 6003.8198854034445, 6018.127371531125, 4493.910121797562, 5903.704814759245, 5973.581849270553, 5973.731182096794, 5986.730597123118, 5565.387807341189, 5781.479666829984, 5917.88164236715, 5926.733297001645, 5952.375000624937, 5680.772769089232, 5782.277215348082, 5786.334571524287, 5787.681677507735, 5817.796377126801, 5072.315237442935, 5074.0, 5083.822066227664, 5086.364204474441, 5356.464353564651, 12581.622551323475, 12781.936090319488, 12836.871631283755, 12846.935765321114, 17949.7307540387, 2133.2107120365563, 2151.7310336207975, 2283.7259528856475, 2285.4743409015855, 2295.483516208856, 9147.77130571741, 9348.813023739554, 9541.713748587554, 9756.658516951873, 10057.748556286151, 1769.5399196209078, 1797.6494583556212, 1817.3730063128332, 1826.1599664154505, 1837.73352863834, 75063.0, 105173.4741326296, 114945.0, 124200.75799241982, 124536.0, 7794.766157015299, 9554.378965367228, 9809.860844529681, 9929.330896809295, 10097.747556311004, 12244.755104897853, 12279.0, 12296.815547766791, 12309.753804923852, 12309.876901231051, 7579.886301705479, 7814.858125193657, 7822.530648161015, 7969.083555391088, 7983.401244906655, 8176.059753128347, 8188.631511581874, 8192.467489613156, 8284.958575207085, 8361.665533378597, 3793.146542028077, 3801.8669346572738, 3818.9427158592644, 3866.420036994417, 3924.293627147141, 1848.9167987440596, 1906.4661894669514, 1906.7044608085769, 1910.646530391882, 1916.626257879728, 748.7903387051634, 790.3598085550723, 837.4012581004489, 903.6927444668844, 907.682311191091, 20729.378118656463, 21199.364019079454, 21523.816190109694, 21528.81591512482, 21706.023228954735, 748.2031636307315, 852.6461518469889, 886.6409104312763, 909.4043401571981, 917.1195652173988, 8453.492790432594, 10443.94778026105, 10888.727781805519, 11211.55153793839, 11251.774964500664, 576689.0, 588715.0, 590757.0, 591830.040849793, 593578.0321098453, 92835.5358223218, 103786.0, 124452.90675302227, 126928.0, 133529.0, 949.3449519831295, 997.4314640654902, 998.5207100591757, 1002.0931057392925, 1008.7276435362411, 14678.559643210721, 19498.31755888537, 19499.8050019498, 19564.510887227363, 19583.510412239237, 44262.89342766457, 53353.0, 54108.0, 55178.0, 56485.15272270837, 79321.0, 79540.0, 81067.18932810596, 81255.0, 81827.18172818434, 6338.524610654127, 7891.802704932423, 7906.802329941797, 7982.0, 8148.83702325962, 10420.583176672848, 12326.198797078161, 12568.874311257136, 12632.05259605515, 12647.557335493213, 10496.0, 10857.239993200401, 10914.399708016135, 11104.944475277573, 11118.38848863288, 9235.76910577241, 10254.743631409274, 10646.73383165427, 10690.89309106899, 11141.275817071864, 10049.045340692623, 10335.328203666737, 10934.234603577674, 11059.115270778157, 11108.500117494894, 7080.929190708027, 7605.847883042419, 7840.686372545034, 7897.960510197412, 8073.273405393543, 9595.42427454355, 9778.462184579916, 10894.346339219672, 11068.833967490333, 11236.55053797839], "value": 8950.417019557111, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.689341451466, 1481.7925490431342, 1622.594351412147, 1799.6400719856033, 1827.2234300422326, 7948.52308861468, 8352.039515455725, 8356.331493480515, 8368.372372072095, 8390.119037501066, 7376.373008294301, 7670.463067585271, 7978.521288722666, 8197.303229225508, 8220.630071646776, 2305.87317697527, 2338.3452633262627, 2338.8830558472096, 2339.5203983183405, 2339.7192336919593, 2060.546679730457, 2323.3146221864504, 2361.811055115595, 2374.893129809163, 2377.263048454976, 2074.284371891695, 2099.4856260216193, 2104.852660313779, 2106.6524023536176, 2106.9894650526726, 2243.5737209930144, 2320.965185522227, 2325.7906788389128, 2327.7672232776586, 2333.3233362325077, 2311.791938725523, 2337.439014636492, 2339.415146213447, 2341.1103780563276, 2350.6944097267415, 8263.049749278789, 8296.709615163501, 8375.20435558621, 8383.203595658402, 8396.286315663176, 5017.172166592508, 5178.585713142939, 5182.4299327073995, 5183.2743415921805, 5185.403678576974, 3338.8664453421834, 3502.439609662442, 3539.752217344787, 3577.195131095483, 3606.711463082947, 1011.3830563356347, 1051.784384201237, 1081.1566977757361, 1095.956161753529, 1111.9666410007712, 10564.947175264151, 10709.25035247533, 10733.62432314873, 10752.301100428447, 10776.0, 4294.248506511348, 4488.035072459437, 4562.954370456319, 4566.292224705176, 4650.372199753057, 9186.678466253783, 9296.349255552046, 9302.581328854547, 9302.86045709315, 9307.3019523536, 5163.199704045879, 5165.173572228426, 5166.225066240019, 5168.121419358718, 5171.741412929414, 4602.585767280961, 4731.574158325767, 4732.148213321635, 4742.596879265301, 4749.64377671676, 3671.210689701727, 3681.5582130144016, 3750.5686846012513, 3812.046988252937, 3844.6347596978244, 1064.6007747094839, 1240.3984067727145, 1242.0746543824907, 1256.7926292161874, 1266.4870727355435, 742.1502379775127, 829.1625458287174, 882.7969566999626, 886.5212785095976, 945.9877930614176, 5305.840824775263, 5572.0, 5651.943480565224, 5864.736086876015, 5908.704564771744, 810.7000409848393, 922.0065800900113, 988.8961659025842, 996.8604395384652, 1042.854000439939, 3520.15516276097, 3680.963190368062, 3743.494628225155, 3773.4528493368307, 3885.533735951704, 9637.71086867395, 10083.798324033625, 10091.293609447415, 10126.898731012596, 10275.7431064224, 9365.344425890258, 10021.599136034623, 10028.997100289913, 10041.548130334153, 10042.146417554444, 7490.737824176236, 7742.4193185510185, 7794.532328060334, 7810.921890781133, 7832.765017049498, 5364.4099149094345, 5459.062032978394, 5536.390996990408, 5573.888522229451, 5613.550915926798, 8833.469991800512, 8943.508107054175, 9211.539423028957, 9246.815063698823, 9368.812623747623, 9286.34995550305, 9311.76720581991, 9392.389494682824, 9438.38650487716, 9488.95255523718, 7394.186639469762, 7673.271039251262, 7811.140774514913, 7816.7264145754625, 7825.334846538108, 7832.530048197126, 7919.485233459806, 8006.399520036137, 8014.479058861155, 8017.799555011172, 5588.804391846266, 6934.341237582422, 6990.2660220678135, 7028.4728645352825, 7161.4628902831455, 6793.626350550767, 6826.897596536155, 6903.792886213421, 7018.298170182941, 7061.399781018671, 6595.406413422815, 6650.600963942179, 6656.700448479927, 6729.966350168414, 6730.788458077593, 3898.200868821828, 4136.544980052132, 4627.236505976476, 4783.330333753347, 4935.407751069894, 4357.368181613585, 4468.151051300242, 4484.529124441887, 4498.145352383037, 4619.452483418093], "value": 4379.5733660340065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.97905832187615, 296.41606036108857, 305.854719008471, 308.71443914379194, 312.5109204095589, 4584.7478388688605, 5218.243354713564, 5299.8410047698635, 5310.230016647583, 5320.813771517997, 4908.803647854082, 5016.419827754255, 5067.366579177603, 5131.743412829363, 5254.737263136847, 4025.7383270087353, 4028.1540876415843, 4063.3295506241584, 4066.0444795473113, 4071.9185616287655, 4034.7377420467724, 4041.4139949707273, 4045.1909618076356, 4048.0, 4063.87808365748, 3717.758345707534, 3926.3325234710023, 3970.5235371755284, 3978.4231286463446, 3982.9843389935527, 3530.064532898784, 3830.693544516432, 3924.82338294776, 3955.1694144229746, 3969.8809035728973, 3032.4844776388068, 3202.9359412811846, 3390.94847617193, 3400.880969166067, 3409.369266685671, 4629.907401851978, 4634.21218392874, 4643.837465688718, 4879.0239952819165, 5087.389513258433, 4305.784710764449, 4456.465224173087, 4534.4582841833935, 4536.160810250114, 4595.885102872421, 5179.326687530597, 5475.972620136914, 5563.777448902039, 5606.271184745998, 5623.831285061455, 3330.833458327098, 3787.6780473659774, 3796.4874741909766, 3802.9429558556644, 3816.084139806454, 4907.288443175719, 5388.299521062276, 5647.265855438808, 5821.505172060338, 5873.882522349529, 3443.345764304774, 3584.211473475831, 3779.7921114338424, 3788.3938569828697, 3821.3568165688466, 3334.3164651246434, 3889.0943437715882, 3897.2595206910596, 3918.294706952719, 3919.1573811630637, 3690.8892733218045, 3941.231459865299, 4257.936130958038, 4435.800388982503, 4497.437820272466, 5559.388467268597, 5567.331920169606, 5582.581306401955, 5583.3858275589655, 5601.451339640454, 1068.8343306787306, 1284.460526578839, 1307.1830106183636, 1325.6213537920648, 1333.7465881482487, 836.9916300837035, 846.7375113714768, 852.4586887326592, 895.4940458640847, 903.6656437118309, 5024.0, 5162.45794191612, 5210.817621383309, 5251.553617942442, 5295.947040529622, 4896.4124305083615, 5559.192607733069, 5641.26663533738, 5666.263385759825, 5727.503909431077, 4358.389825424443, 4403.965068209032, 4671.43942726875, 5286.788528458896, 5312.229726689686, 2524.911628093008, 2594.0272397850804, 2783.8747256373513, 2977.389635124794, 2995.495270533481, 4609.562091601292, 5706.9714651426475, 5736.942630573724, 5755.510781583528, 5801.622894511843, 5816.975172910148, 5838.211841401441, 5845.240118784531, 5849.298084229835, 5851.736671849777, 4810.478150538553, 5771.903338365697, 5788.80025590273, 5800.709964501758, 5867.354590994987, 4533.320001999727, 5401.135818269058, 5611.410801865909, 5638.688137863456, 5662.357916204254, 4818.445878723852, 5310.814121505883, 5614.438556144353, 5653.145161693318, 5665.546756259571, 5044.117279476003, 5407.648502847303, 5740.540756739368, 5768.307803063659, 5794.82615521535, 3497.667721566447, 3616.3129005488872, 3758.924821503609, 3797.886063418102, 3836.4437156612694, 4941.899692630545, 4960.172701560076, 5578.748956296895, 5627.606067575231, 5658.717064146858, 5322.323468107534, 5513.979913715975, 5544.75048622821, 5547.55619550443, 5598.30511862169, 4893.412790465167, 4925.359703238556, 5489.1217405214975, 5504.0, 5518.420565840529, 5122.0, 5326.0945639240645, 5335.706536140394, 5408.405075441619, 5427.185922111715, 4787.688800227974, 5224.190250511177, 5487.972560137174, 5493.533049690661, 5497.560195184456, 4744.857654270378, 5341.092014357491, 5351.708699150073, 5424.105022671215, 5520.611114164169], "value": 4010.1492534180747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.3565919354194, 718.4611541343992, 757.303280981497, 790.8892755014302, 792.5957761541612, 14646.780298295535, 14787.556373308791, 14804.25978701066, 14819.110853348806, 14844.92577537112, 13117.54088606899, 13226.338683065856, 13230.404631791569, 13272.20366777994, 13279.402426890789, 648.8021153548177, 670.3999920071533, 674.5244602555186, 806.4999700185894, 827.1563005734148, 822.4736168851939, 836.1930736838949, 837.7696133563257, 839.014158363923, 839.3998291221775, 890.1587999340626, 890.6660002499062, 892.1702816380763, 893.2496702769672, 894.0924961164424, 689.7137687859532, 712.1525384792133, 851.1828644501286, 889.1863944490783, 889.5552223888088, 645.5674697952384, 649.8375406148463, 650.1645385679379, 650.7527139686936, 651.775137577535, 13800.447982080705, 13900.652483687989, 13931.930340348335, 13935.442582296697, 13944.721105577832, 12796.424160912688, 12866.035047371484, 12919.289439080847, 13035.348232588427, 13054.151480153854, 11264.43677816106, 11904.880951190551, 11954.462049207807, 12127.696807579792, 12194.0, 4342.5874541918465, 4376.584224498668, 4438.112377524508, 4464.241079016575, 4579.3131030345385, 86422.27155456919, 86439.13560864374, 87086.5645671774, 87606.0, 88549.0, 10267.845982310271, 10298.227632927561, 10314.27800054004, 10371.740706482398, 10734.355938643706, 18972.60820955129, 19300.71048934267, 19348.903255483634, 19481.0, 19522.511937201685, 6464.676766161673, 7477.102747670315, 7516.624168791539, 7690.077190737147, 7758.262965018314, 4125.319322311785, 4177.91644167121, 4188.267053265667, 4236.406903033578, 4312.676549258818, 3158.873645054218, 3219.146926064595, 3248.2204270974794, 3253.2192273854102, 3265.689759472846, 602.5902386377305, 606.1866698362722, 609.7286707415246, 628.2743431336813, 635.7697854651265, 567.4013915319377, 576.6367188671194, 628.4281304013326, 653.3074940563018, 656.2682608891052, 8629.61166747498, 8939.329550283755, 8986.86519702205, 9120.0, 9164.495952722664, 521.559282406366, 650.0218661789876, 653.7711800869657, 662.0995446193174, 669.5480550628306, 4222.451081359403, 4395.846145384896, 4417.425734654475, 4468.910621787546, 4524.411826462539, 108076.4596177014, 108631.0, 109553.45223273833, 110203.0, 111300.0, 84603.0, 87587.0, 91569.0, 93026.0, 94698.52650736702, 76517.0, 82436.58781706174, 83210.75183872126, 84152.15847841444, 85305.0, 8493.532855692996, 8507.914920850711, 8529.616167272612, 8671.913280867111, 8721.738347849574, 42706.0, 50331.74834125806, 50570.0, 50986.23520647119, 55294.72352638211, 67516.0, 70234.0, 70771.58456830731, 72302.63848680723, 77729.61135194288, 12295.262284262973, 12370.44333004999, 12502.499900003899, 12799.744005120032, 12824.551140710404, 13910.51313204033, 14220.786688199478, 14560.0, 14659.340329685403, 14739.336729847397, 11988.340641264815, 12683.492660293854, 12736.68158296013, 12788.616341509769, 12806.743865122831, 12972.545960891324, 13233.801492977806, 13458.932705336802, 13481.797773033217, 13665.316734163052, 12440.937795310965, 12479.31363775001, 12792.360381980678, 13016.804747928978, 13136.868631313564, 10714.57141714354, 10897.891021090005, 10973.561057557608, 11047.116230701686, 11126.944365278121, 9562.808743825224, 9640.373375730554, 10394.58421663125, 11038.061764749766, 11135.0], "value": 7201.202864076945, "unit": "score"}], "name": "dromaeo_css", "value": 7034.139254434823, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 112.0, 150.0, 114.0, 113.0, 110.0, 110.0, 144.0, 114.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 184.0, 184.0, 183.0, 181.0, 181.0, 183.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 88.0, 63.0, 60.0, 58.0, 56.0, 83.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 90.0, 70.0, 68.0, 69.0, 88.0, 68.0, 68.0, 92.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 92.0, 133.0, 129.0, 112.0, 86.0, 90.0, 87.0, 91.0, 87.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 99.0, 102.0, 98.0, 98.0, 97.0, 98.0, 97.0, 98.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 119.0, 80.0, 83.0, 99.0, 122.0, 84.0, 82.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 79.0, 75.0, 73.0, 71.0, 70.0, 71.0, 70.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 85.0, 86.0, 85.0, 89.0, 89.0, 89.0, 86.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 164.0, 156.0, 142.0, 156.0, 156.0, 138.0, 155.0, 219.0, 185.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 195.0, 182.0, 238.0, 180.0, 175.0, 176.0, 174.0, 173.0, 173.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 73.0, 86.0, 89.0, 88.0, 75.0, 65.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1496.6, "alertThreshold": 2.0}]} 20:25:10 INFO - Return code: 0 20:25:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:25:10 INFO - # TBPL SUCCESS # 20:25:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:25:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:25:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:25:11 INFO - 2016-11-02 20:25:11.010 screenresolution[1960:18977] starting screenresolution argv=screenresolution get 20:25:11 INFO - 2016-11-02 20:25:11.014 screenresolution[1960:18977] Display 0: 1600x1200x32@60 20:25:11 INFO - 2016-11-02 20:25:11.020 screenresolution[1961:18979] starting screenresolution argv=screenresolution list 20:25:11 INFO - Available Modes on Display 0 20:25:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:25:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:25:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:25:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:25:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:25:11 INFO - Intel Iris: 20:25:11 INFO - Chipset Model: Intel Iris 20:25:11 INFO - Type: GPU 20:25:11 INFO - Bus: Built-In 20:25:11 INFO - VRAM (Dynamic, Max): 1536 MB 20:25:11 INFO - Vendor: Intel (0x8086) 20:25:11 INFO - Device ID: 0x0a2e 20:25:11 INFO - Revision ID: 0x0009 20:25:11 INFO - Displays: 20:25:11 INFO - Display: 20:25:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:25:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:25:11 INFO - Display Serial Number: 1600x1200 60 20:25:11 INFO - Main Display: Yes 20:25:11 INFO - Mirror: Off 20:25:11 INFO - Online: Yes 20:25:11 INFO - Rotation: Supported 20:25:11 INFO - Return code: 0 20:25:11 INFO - Running post-action listener: _resource_record_post_action 20:25:11 INFO - [mozharness: 2016-11-03 03:25:11.262201Z] Finished run-tests step (success) 20:25:11 INFO - Running post-run listener: _resource_record_post_run 20:25:11 INFO - Total resource usage - Wall time: 1216s; CPU: 54.0%; Read bytes: 950272; Write bytes: 711014400; Read time: 28; Write time: 2140 20:25:11 INFO - TinderboxPrint: CPU usage
53.6% 20:25:11 INFO - TinderboxPrint: I/O read bytes / time
950,272 / 28 20:25:11 INFO - TinderboxPrint: I/O write bytes / time
711,014,400 / 2,140 20:25:11 INFO - TinderboxPrint: CPU idle
2,259.3 (46.4%) 20:25:11 INFO - TinderboxPrint: CPU system
973.1 (20.0%) 20:25:11 INFO - TinderboxPrint: CPU user
1,633.4 (33.6%) 20:25:11 INFO - TinderboxPrint: Swap in / out
406,695,936 / 0 20:25:11 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390269440; Write bytes: 386862592; Read time: 29242; Write time: 757 20:25:11 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 950272; Write bytes: 298832896; Read time: 28; Write time: 1318 20:25:11 INFO - Running post-run listener: _upload_blobber_files 20:25:11 INFO - Blob upload gear active. 20:25:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:25:11 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 20:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:25:12 INFO - (blobuploader) - INFO - Open directory for files ... 20:25:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:25:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:12 INFO - (blobuploader) - INFO - Done attempting. 20:25:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:25:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:13 INFO - (blobuploader) - INFO - Done attempting. 20:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:25:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:16 INFO - (blobuploader) - INFO - Done attempting. 20:25:16 INFO - (blobuploader) - INFO - Iteration through files over. 20:25:16 INFO - Return code: 0 20:25:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:25:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:25:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e19c4556f618acc42f6a998111574f0ec532afc8e1e637420219d8bd47c2fc434bcd60165d7b202b5a4a7d4f1bca98765668a38bd082c8001536d18505042d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc8de33108e0db1ea8cc5b817c3b3ab4310c14b90fb46b428c5a20f75333736b23d64f2862919e5d59ee55e49da422b420b27648bafc441a1150b2f4b08a51d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86d208d8753c517171f631f0270f22bcdd551f457400f3a8ea61c3de80e75ce927040681d1c2fc0a2c31fc31e57a68192040090ee03f3ec48341fefa1b661d88"} 20:25:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:25:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:25:16 INFO - Contents: 20:25:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e19c4556f618acc42f6a998111574f0ec532afc8e1e637420219d8bd47c2fc434bcd60165d7b202b5a4a7d4f1bca98765668a38bd082c8001536d18505042d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc8de33108e0db1ea8cc5b817c3b3ab4310c14b90fb46b428c5a20f75333736b23d64f2862919e5d59ee55e49da422b420b27648bafc441a1150b2f4b08a51d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86d208d8753c517171f631f0270f22bcdd551f457400f3a8ea61c3de80e75ce927040681d1c2fc0a2c31fc31e57a68192040090ee03f3ec48341fefa1b661d88"} 20:25:16 INFO - Running post-run listener: copy_logs_to_upload_dir 20:25:16 INFO - Copying logs to upload dir... 20:25:16 INFO - mkdir: /builds/slave/test/build/upload/logs 20:25:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.545240 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-02 20:25:16.668269) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:25:16.675742) ========= 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.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e19c4556f618acc42f6a998111574f0ec532afc8e1e637420219d8bd47c2fc434bcd60165d7b202b5a4a7d4f1bca98765668a38bd082c8001536d18505042d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc8de33108e0db1ea8cc5b817c3b3ab4310c14b90fb46b428c5a20f75333736b23d64f2862919e5d59ee55e49da422b420b27648bafc441a1150b2f4b08a51d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86d208d8753c517171f631f0270f22bcdd551f457400f3a8ea61c3de80e75ce927040681d1c2fc0a2c31fc31e57a68192040090ee03f3ec48341fefa1b661d88"} build_url:https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e19c4556f618acc42f6a998111574f0ec532afc8e1e637420219d8bd47c2fc434bcd60165d7b202b5a4a7d4f1bca98765668a38bd082c8001536d18505042d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc8de33108e0db1ea8cc5b817c3b3ab4310c14b90fb46b428c5a20f75333736b23d64f2862919e5d59ee55e49da422b420b27648bafc441a1150b2f4b08a51d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86d208d8753c517171f631f0270f22bcdd551f457400f3a8ea61c3de80e75ce927040681d1c2fc0a2c31fc31e57a68192040090ee03f3ec48341fefa1b661d88"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:25:16.707669) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:25:16.707973) ========= 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.TdPMb15mAn/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.lDugaCIGvR/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005218 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:25:16.758958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:25:16.759256) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:25:16.762575) ========= ========= Total master_lag: 0.20 =========