builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1476460780.58 results: success (0) buildid: 20161014060236 builduid: 7478043fa8514665a2e0d751830488d6 revision: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.579725) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.580162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.580435) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.604109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.604368) ========= 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.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-14 08:59:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-10-14 08:59:40 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.751922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.752281) ========= 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.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.026669 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:40.800317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 08:59:40.803683) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c899672fff6ae00f5b3affbec48ee4daac35fa1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0c899672fff6ae00f5b3affbec48ee4daac35fa1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-14 08:59:40,885 truncating revision to first 12 chars 2016-10-14 08:59:40,885 Setting DEBUG logging. 2016-10-14 08:59:40,885 attempt 1/10 2016-10-14 08:59:40,885 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c899672fff6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 08:59:41,907 unpacking tar archive at: fx-team-0c899672fff6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.274919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 08:59:42.100320) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:42.103661) ========= script_repo_revision: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:42.104045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:42.104310) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:59:42.122501) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-14 08:59:42.122876) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 08:59:42 INFO - MultiFileLogger online at 20161014 08:59:42 in /builds/slave/test 08:59:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:59:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:42 INFO - {'append_to_log': False, 08:59:42 INFO - 'base_work_dir': '/builds/slave/test', 08:59:42 INFO - 'blob_upload_branch': 'Fx-Team', 08:59:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:42 INFO - 'branch': 'Fx-Team', 08:59:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:42 INFO - 'config_files': ('talos/mac_config.py',), 08:59:42 INFO - 'default_actions': ('clobber', 08:59:42 INFO - 'read-buildbot-config', 08:59:42 INFO - 'download-and-extract', 08:59:42 INFO - 'populate-webroot', 08:59:42 INFO - 'create-virtualenv', 08:59:42 INFO - 'install', 08:59:42 INFO - 'run-tests'), 08:59:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:42 INFO - 'download_minidump_stackwalk': True, 08:59:42 INFO - 'download_symbols': 'ondemand', 08:59:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:42 INFO - 'installer_path': 'installer.exe', 08:59:42 INFO - 'log_level': 'info', 08:59:42 INFO - 'log_name': 'talos', 08:59:42 INFO - 'log_to_console': True, 08:59:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:59:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:59:42 INFO - 'opt_config_files': (), 08:59:42 INFO - 'pip_index': False, 08:59:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:42 INFO - 'cmd': ('bash', 08:59:42 INFO - '-c', 08:59:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:42 INFO - 'enabled': True, 08:59:42 INFO - 'halt_on_failure': False, 08:59:42 INFO - 'name': 'check_screen_resolution'},), 08:59:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:42 INFO - 'cmd': ('bash', 08:59:42 INFO - '-c', 08:59:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:42 INFO - 'enabled': True, 08:59:42 INFO - 'halt_on_failure': False, 08:59:42 INFO - 'name': 'check_screen_resolution'},), 08:59:42 INFO - 'run_cmd_checks_enabled': True, 08:59:42 INFO - 'sps_profile': False, 08:59:42 INFO - 'sps_profile_interval': 0, 08:59:42 INFO - 'suite': 'dromaeojs', 08:59:42 INFO - 'system_bits': '32', 08:59:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:42 INFO - 'title': 't-yosemite-r7-0228', 08:59:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:42 INFO - 'use_talos_json': True, 08:59:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:42 INFO - 'work_dir': 'build'} 08:59:42 INFO - [mozharness: 2016-10-14 15:59:42.323214Z] Running clobber step. 08:59:42 INFO - Running pre-action listener: _resource_record_pre_action 08:59:42 INFO - Running main action method: clobber 08:59:42 INFO - rmtree: /builds/slave/test/build 08:59:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - [mozharness: 2016-10-14 15:59:44.344422Z] Finished clobber step (success) 08:59:44 INFO - [mozharness: 2016-10-14 15:59:44.344498Z] Running read-buildbot-config step. 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: read_buildbot_config 08:59:44 INFO - Using buildbot properties: 08:59:44 INFO - { 08:59:44 INFO - "project": "", 08:59:44 INFO - "product": "firefox", 08:59:44 INFO - "script_repo_revision": "production", 08:59:44 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:59:44 INFO - "repository": "", 08:59:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:59:44 INFO - "buildid": "20161014060236", 08:59:44 INFO - "pgo_build": "False", 08:59:44 INFO - "basedir": "/builds/slave/test", 08:59:44 INFO - "buildnumber": 184, 08:59:44 INFO - "slavename": "t-yosemite-r7-0228", 08:59:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:59:44 INFO - "platform": "yosemite_r7", 08:59:44 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:59:44 INFO - "branch": "fx-team", 08:59:44 INFO - "repo_path": "integration/fx-team", 08:59:44 INFO - "revision": "0c899672fff6ae00f5b3affbec48ee4daac35fa1", 08:59:44 INFO - "stage_platform": "macosx64", 08:59:44 INFO - "builduid": "7478043fa8514665a2e0d751830488d6", 08:59:44 INFO - "slavebuilddir": "test" 08:59:44 INFO - } 08:59:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - [mozharness: 2016-10-14 15:59:44.347888Z] Finished read-buildbot-config step (success) 08:59:44 INFO - [mozharness: 2016-10-14 15:59:44.348037Z] Running download-and-extract step. 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: download_and_extract 08:59:44 INFO - mkdir: /builds/slave/test/build/tests 08:59:44 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')]} 08:59:44 INFO - trying https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:59:44 INFO - Downloading https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/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 08:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/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 08:59:45 INFO - Downloaded 1199 bytes. 08:59:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:59:45 INFO - Using the following test package requirements: 08:59:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:59:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:59:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:59:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'jsshell-mac.zip'], 08:59:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:59:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:59:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:59:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:59:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:59:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:59:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:59:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:59:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:59:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:45 INFO - Fetch https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:59:46 INFO - Http code: 200 08:59:46 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 08:59:46 INFO - x-amz-cf-id: LqddRHlK9gOe1ST4n2qULUnjLeznz5qA5alV9O9pa48MQVmEvf-iFA== 08:59:46 INFO - x-amz-version-id: 4GqmR2YMNE9vlv7DbUTmwYggMKhz_4q. 08:59:46 INFO - Expected file size: 16516955 08:59:46 INFO - Obtained file size: 16516955 08:59:53 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 08:59:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:59:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:53 INFO - Fetch https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:59:53 INFO - Http code: 200 08:59:53 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 08:59:53 INFO - x-amz-cf-id: 16F3-XH7-3Z1pWMx3Jc-6eRfn9NO6sQkImq68Y-Y49IbMwvFfVdeEQ== 08:59:53 INFO - x-amz-version-id: 4GqmR2YMNE9vlv7DbUTmwYggMKhz_4q. 08:59:54 INFO - Expected file size: 16516955 08:59:54 INFO - Obtained file size: 16516955 09:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:00:01 INFO - Http code: 200 09:00:01 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 09:00:01 INFO - x-amz-cf-id: 2FVeLhk1fG2q8jrsbT5c2f6KCr-GGi55GnSrypiBeC-Jwf6le---VQ== 09:00:01 INFO - x-amz-version-id: x3sa9RwhAgWW5TdEQNtC0pGE6TviijEx 09:00:01 INFO - Expected file size: 14409006 09:00:01 INFO - Obtained file size: 14409006 09:00:01 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')]} 09:00:01 INFO - trying https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:00:01 INFO - Downloading https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/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 09:00:04 INFO - Downloaded 111921419 bytes. 09:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:00:04 INFO - mkdir: /builds/slave/test/properties 09:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:04 INFO - Contents: 09:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:00:05 INFO - Running post-action listener: _resource_record_post_action 09:00:05 INFO - Running post-action listener: set_extra_try_arguments 09:00:05 INFO - [mozharness: 2016-10-14 16:00:05.425302Z] Finished download-and-extract step (success) 09:00:05 INFO - [mozharness: 2016-10-14 16:00:05.425423Z] Running populate-webroot step. 09:00:05 INFO - Running pre-action listener: _resource_record_pre_action 09:00:05 INFO - Running main action method: populate_webroot 09:00:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:05 INFO - '--tppagecycles', 09:00:05 INFO - '7'], 09:00:05 INFO - 'tests': ['tsvgm']}}, 09:00:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['damp', 'tps']}, 09:00:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['damp', 'tps']}, 09:00:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['dromaeo_dom']}, 09:00:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['basic_compositor_video']}, 09:00:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tsvgx', 09:00:05 INFO - 'tsvgr_opacity', 09:00:05 INFO - 'tart', 09:00:05 INFO - 'tscrollx', 09:00:05 INFO - 'cart']}, 09:00:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:05 INFO - 'tsvgr_opacity', 09:00:05 INFO - 'tart', 09:00:05 INFO - 'tscrollx', 09:00:05 INFO - 'cart']}, 09:00:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tp5o']}, 09:00:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['tp5o']}, 09:00:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s', 09:00:05 INFO - '--xperf_path', 09:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:05 INFO - 'tests': ['tp5n']}, 09:00:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--xperf_path', 09:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:05 INFO - 'tests': ['tp5n']}}, 09:00:05 INFO - 'talos.zip': {'path': '', 09:00:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:05 INFO - Running post-action listener: _resource_record_post_action 09:00:05 INFO - [mozharness: 2016-10-14 16:00:05.444535Z] Finished populate-webroot step (success) 09:00:05 INFO - [mozharness: 2016-10-14 16:00:05.444671Z] Running create-virtualenv step. 09:00:05 INFO - Running pre-action listener: _resource_record_pre_action 09:00:05 INFO - Running main action method: create_virtualenv 09:00:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:05 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 09:00:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:05 INFO - Using real prefix '/tools/python27' 09:00:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:09 INFO - Installing pip.................done. 09:00:09 INFO - Return code: 0 09:00:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:09 INFO - Reading from file tmpfile_stdout 09:00:09 INFO - Output received: 09:00:09 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) 09:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:09 INFO - 'HOME': '/Users/cltbld', 09:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:09 INFO - 'LOGNAME': 'cltbld', 09:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:09 INFO - 'MOZ_NO_REMOTE': '1', 09:00:09 INFO - 'NO_EM_RESTART': '1', 09:00:09 INFO - 'PAGER': '/bin/cat', 09:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:09 INFO - 'PWD': '/builds/slave/test', 09:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:09 INFO - 'SHELL': '/bin/bash', 09:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:09 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:09 INFO - 'USER': 'cltbld', 09:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:09 INFO - 'XPC_FLAGS': '0x0', 09:00:09 INFO - 'XPC_SERVICE_NAME': '0', 09:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:09 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 09:00:09 INFO - Cleaning up... 09:00:09 INFO - Return code: 0 09:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:09 INFO - 'HOME': '/Users/cltbld', 09:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:09 INFO - 'LOGNAME': 'cltbld', 09:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:09 INFO - 'MOZ_NO_REMOTE': '1', 09:00:09 INFO - 'NO_EM_RESTART': '1', 09:00:09 INFO - 'PAGER': '/bin/cat', 09:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:09 INFO - 'PWD': '/builds/slave/test', 09:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:09 INFO - 'SHELL': '/bin/bash', 09:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:09 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:09 INFO - 'USER': 'cltbld', 09:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:09 INFO - 'XPC_FLAGS': '0x0', 09:00:09 INFO - 'XPC_SERVICE_NAME': '0', 09:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:09 INFO - Downloading/unpacking psutil>=3.1.1 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:13 INFO - Installing collected packages: psutil 09:00:13 INFO - Running setup.py install for psutil 09:00:13 INFO - building 'psutil._psutil_osx' extension 09:00:13 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 09:00:13 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 09:00:13 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 09:00:14 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 09:00:14 INFO - building 'psutil._psutil_posix' extension 09:00:14 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 09:00:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:00:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:00:14 INFO - ^ 09:00:14 INFO - 1 warning generated. 09:00:14 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 09:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:14 INFO - Successfully installed psutil 09:00:14 INFO - Cleaning up... 09:00:14 INFO - Return code: 0 09:00:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:14 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 09:00:14 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 09:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:14 INFO - 'HOME': '/Users/cltbld', 09:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:14 INFO - 'LOGNAME': 'cltbld', 09:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:14 INFO - 'MOZ_NO_REMOTE': '1', 09:00:14 INFO - 'NO_EM_RESTART': '1', 09:00:14 INFO - 'PAGER': '/bin/cat', 09:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:14 INFO - 'PWD': '/builds/slave/test', 09:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:14 INFO - 'SHELL': '/bin/bash', 09:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:14 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:14 INFO - 'USER': 'cltbld', 09:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:14 INFO - 'XPC_FLAGS': '0x0', 09:00:14 INFO - 'XPC_SERVICE_NAME': '0', 09:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:17 INFO - Installing collected packages: mozsystemmonitor 09:00:17 INFO - Running setup.py install for mozsystemmonitor 09:00:17 INFO - Successfully installed mozsystemmonitor 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:17 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')]} 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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')]} 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:17 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 09:00:17 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 09:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:17 INFO - 'HOME': '/Users/cltbld', 09:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:17 INFO - 'LOGNAME': 'cltbld', 09:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:17 INFO - 'MOZ_NO_REMOTE': '1', 09:00:17 INFO - 'NO_EM_RESTART': '1', 09:00:17 INFO - 'PAGER': '/bin/cat', 09:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:17 INFO - 'PWD': '/builds/slave/test', 09:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:17 INFO - 'SHELL': '/bin/bash', 09:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:17 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:17 INFO - 'USER': 'cltbld', 09:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:17 INFO - 'XPC_FLAGS': '0x0', 09:00:17 INFO - 'XPC_SERVICE_NAME': '0', 09:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:20 INFO - warning: no files found matching '*.txt' 09:00:20 INFO - no previously-included directories found matching 'build' 09:00:20 INFO - no previously-included directories found matching 'dist' 09:00:20 INFO - no previously-included directories found matching '.git*' 09:00:20 INFO - Installing collected packages: jsonschema, functools32 09:00:20 INFO - Running setup.py install for functools32 09:00:20 INFO - warning: no files found matching '*.txt' 09:00:20 INFO - no previously-included directories found matching 'build' 09:00:20 INFO - no previously-included directories found matching 'dist' 09:00:20 INFO - no previously-included directories found matching '.git*' 09:00:21 INFO - Successfully installed jsonschema functools32 09:00:21 INFO - Cleaning up... 09:00:21 INFO - Return code: 0 09:00:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:21 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')]} 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 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')]} 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:21 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 09:00:21 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 09:00:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:21 INFO - 'HOME': '/Users/cltbld', 09:00:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:21 INFO - 'LOGNAME': 'cltbld', 09:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:21 INFO - 'MOZ_NO_REMOTE': '1', 09:00:21 INFO - 'NO_EM_RESTART': '1', 09:00:21 INFO - 'PAGER': '/bin/cat', 09:00:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:21 INFO - 'PWD': '/builds/slave/test', 09:00:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:21 INFO - 'SHELL': '/bin/bash', 09:00:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:21 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:21 INFO - 'USER': 'cltbld', 09:00:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:21 INFO - 'XPC_FLAGS': '0x0', 09:00:21 INFO - 'XPC_SERVICE_NAME': '0', 09:00:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:21 INFO - Cleaning up... 09:00:21 INFO - Return code: 0 09:00:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:21 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')]} 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 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')]} 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:21 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 09:00:21 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 09:00:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:21 INFO - 'HOME': '/Users/cltbld', 09:00:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:21 INFO - 'LOGNAME': 'cltbld', 09:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:21 INFO - 'MOZ_NO_REMOTE': '1', 09:00:21 INFO - 'NO_EM_RESTART': '1', 09:00:21 INFO - 'PAGER': '/bin/cat', 09:00:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:21 INFO - 'PWD': '/builds/slave/test', 09:00:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:21 INFO - 'SHELL': '/bin/bash', 09:00:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:21 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:21 INFO - 'USER': 'cltbld', 09:00:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:21 INFO - 'XPC_FLAGS': '0x0', 09:00:21 INFO - 'XPC_SERVICE_NAME': '0', 09:00:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:25 INFO - Downloading docopt-0.6.1.tar.gz 09:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:25 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:25 INFO - Running setup.py install for blobuploader 09:00:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:25 INFO - Running setup.py install for requests 09:00:26 INFO - Running setup.py install for docopt 09:00:26 INFO - Successfully installed blobuploader requests docopt 09:00:26 INFO - Cleaning up... 09:00:26 INFO - Return code: 0 09:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:26 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 09:00:26 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 09:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:26 INFO - 'HOME': '/Users/cltbld', 09:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:26 INFO - 'LOGNAME': 'cltbld', 09:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:26 INFO - 'MOZ_NO_REMOTE': '1', 09:00:26 INFO - 'NO_EM_RESTART': '1', 09:00:26 INFO - 'PAGER': '/bin/cat', 09:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:26 INFO - 'PWD': '/builds/slave/test', 09:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:26 INFO - 'SHELL': '/bin/bash', 09:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:26 INFO - 'USER': 'cltbld', 09:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:26 INFO - 'XPC_FLAGS': '0x0', 09:00:26 INFO - 'XPC_SERVICE_NAME': '0', 09:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5jTqhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-mxA_uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jNGZw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-BlIGYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Yz_QLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-bUDZEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-IbikML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yctnoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tPzt3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-_JT9qM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-mrImCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-qviKHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xw35Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PtXIPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yNZNKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:28 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zE1pBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:28 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gsfMxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:28 INFO - Running setup.py install for manifestparser 09:00:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:28 INFO - Running setup.py install for mozcrash 09:00:28 INFO - Running setup.py install for mozdebug 09:00:28 INFO - Running setup.py install for mozdevice 09:00:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:28 INFO - Running setup.py install for mozfile 09:00:29 INFO - Running setup.py install for mozhttpd 09:00:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Running setup.py install for mozinfo 09:00:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Running setup.py install for mozInstall 09:00:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Running setup.py install for mozleak 09:00:29 INFO - Running setup.py install for mozlog 09:00:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Running setup.py install for moznetwork 09:00:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozprocess 09:00:30 INFO - Running setup.py install for mozprofile 09:00:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozrunner 09:00:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Running setup.py install for mozscreenshot 09:00:30 INFO - Running setup.py install for moztest 09:00:30 INFO - Running setup.py install for mozversion 09:00:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:30 INFO - Cleaning up... 09:00:30 INFO - Return code: 0 09:00:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:30 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:30 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 09:00:30 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 09:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:30 INFO - 'HOME': '/Users/cltbld', 09:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:30 INFO - 'LOGNAME': 'cltbld', 09:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:30 INFO - 'MOZ_NO_REMOTE': '1', 09:00:30 INFO - 'NO_EM_RESTART': '1', 09:00:30 INFO - 'PAGER': '/bin/cat', 09:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:30 INFO - 'PWD': '/builds/slave/test', 09:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:30 INFO - 'SHELL': '/bin/bash', 09:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:30 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:30 INFO - 'USER': 'cltbld', 09:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:30 INFO - 'XPC_FLAGS': '0x0', 09:00:30 INFO - 'XPC_SERVICE_NAME': '0', 09:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-JNHodO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QJVQqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-dyXYzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Rr23RS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-OktawX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kQoZdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-fbt459-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:31 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)) 09:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:31 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Kf1FAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-pkb7g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xpuoJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uag7k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uS72PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-io_ws9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RIf2Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-NC6mbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-R6WqrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:32 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)) 09:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WdtZt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:33 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)) 09:00:33 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)) 09:00:33 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)) 09:00:33 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)) 09:00:33 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)) 09:00:33 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)) 09:00:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:35 INFO - Downloading blessings-1.6.tar.gz 09:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:35 INFO - Installing collected packages: blessings 09:00:35 INFO - Running setup.py install for blessings 09:00:35 INFO - Successfully installed blessings 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:36 INFO - Reading from file tmpfile_stdout 09:00:36 INFO - Current package versions: 09:00:36 INFO - blessings == 1.6 09:00:36 INFO - blobuploader == 1.2.4 09:00:36 INFO - docopt == 0.6.1 09:00:36 INFO - functools32 == 3.2.3-2 09:00:36 INFO - jsonschema == 2.5.1 09:00:36 INFO - manifestparser == 1.1 09:00:36 INFO - mozInstall == 1.12 09:00:36 INFO - mozcrash == 0.17 09:00:36 INFO - mozdebug == 0.1 09:00:36 INFO - mozdevice == 0.48 09:00:36 INFO - mozfile == 1.2 09:00:36 INFO - mozhttpd == 0.7 09:00:36 INFO - mozinfo == 0.9 09:00:36 INFO - mozleak == 0.1 09:00:36 INFO - mozlog == 3.4 09:00:36 INFO - moznetwork == 0.27 09:00:36 INFO - mozprocess == 0.23 09:00:36 INFO - mozprofile == 0.28 09:00:36 INFO - mozrunner == 6.12 09:00:36 INFO - mozscreenshot == 0.1 09:00:36 INFO - mozsystemmonitor == 0.3 09:00:36 INFO - moztest == 0.7 09:00:36 INFO - mozversion == 1.4 09:00:36 INFO - psutil == 3.1.1 09:00:36 INFO - requests == 1.2.3 09:00:36 INFO - wsgiref == 0.1.2 09:00:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:37 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 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:37 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 09:00:37 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 09:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:37 INFO - 'HOME': '/Users/cltbld', 09:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:37 INFO - 'LOGNAME': 'cltbld', 09:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:37 INFO - 'MOZ_NO_REMOTE': '1', 09:00:37 INFO - 'NO_EM_RESTART': '1', 09:00:37 INFO - 'PAGER': '/bin/cat', 09:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:37 INFO - 'PWD': '/builds/slave/test', 09:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:37 INFO - 'SHELL': '/bin/bash', 09:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:37 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:37 INFO - 'USER': 'cltbld', 09:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:37 INFO - 'XPC_FLAGS': '0x0', 09:00:37 INFO - 'XPC_SERVICE_NAME': '0', 09:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 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)) 09:00:37 INFO - Cleaning up... 09:00:37 INFO - Return code: 0 09:00:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb210d88580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, '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.nvGnE4W6we/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.SRUynctRvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 09:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:37 INFO - 'HOME': '/Users/cltbld', 09:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:37 INFO - 'LOGNAME': 'cltbld', 09:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:37 INFO - 'MOZ_NO_REMOTE': '1', 09:00:37 INFO - 'NO_EM_RESTART': '1', 09:00:37 INFO - 'PAGER': '/bin/cat', 09:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:37 INFO - 'PWD': '/builds/slave/test', 09:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:37 INFO - 'SHELL': '/bin/bash', 09:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:00:37 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:37 INFO - 'USER': 'cltbld', 09:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:37 INFO - 'XPC_FLAGS': '0x0', 09:00:37 INFO - 'XPC_SERVICE_NAME': '0', 09:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:38 INFO - Cleaning up... 09:00:38 INFO - Return code: 0 09:00:38 INFO - Running post-action listener: _resource_record_post_action 09:00:38 INFO - Running post-action listener: _start_resource_monitoring 09:00:38 INFO - Starting resource monitoring. 09:00:38 INFO - [mozharness: 2016-10-14 16:00:38.262848Z] Finished create-virtualenv step (success) 09:00:38 INFO - [mozharness: 2016-10-14 16:00:38.263887Z] Running install step. 09:00:38 INFO - Running pre-action listener: _resource_record_pre_action 09:00:38 INFO - Running main action method: install 09:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:38 INFO - Reading from file tmpfile_stdout 09:00:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:38 INFO - Reading from file tmpfile_stdout 09:00:38 INFO - Output received: 09:00:38 INFO - Usage: mozinstall [options] installer 09:00:38 INFO - Options: 09:00:38 INFO - -h, --help show this help message and exit 09:00:38 INFO - -d DEST, --destination=DEST 09:00:38 INFO - Directory to install application into. [default: 09:00:38 INFO - "/builds/slave/test"] 09:00:38 INFO - --app=APP Application being installed. [default: firefox] 09:00:38 INFO - mkdir: /builds/slave/test/build/application 09:00:38 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'] 09:00:38 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 09:01:12 INFO - Reading from file tmpfile_stdout 09:01:12 INFO - Output received: 09:01:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - [mozharness: 2016-10-14 16:01:12.695065Z] Finished install step (success) 09:01:12 INFO - [mozharness: 2016-10-14 16:01:12.695213Z] Running run-tests step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: run_tests 09:01:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:12 INFO - 2016-10-14 09:01:12.709 screenresolution[1645:7542] starting screenresolution argv=screenresolution get 09:01:12 INFO - 2016-10-14 09:01:12.712 screenresolution[1645:7542] Display 0: 1600x1200x32@60 09:01:12 INFO - 2016-10-14 09:01:12.718 screenresolution[1646:7544] starting screenresolution argv=screenresolution list 09:01:12 INFO - Available Modes on Display 0 09:01:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:12 INFO - Intel Iris: 09:01:12 INFO - Chipset Model: Intel Iris 09:01:12 INFO - Type: GPU 09:01:12 INFO - Bus: Built-In 09:01:12 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:12 INFO - Vendor: Intel (0x8086) 09:01:12 INFO - Device ID: 0x0a2e 09:01:12 INFO - Revision ID: 0x0009 09:01:12 INFO - Displays: 09:01:12 INFO - Display: 09:01:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:12 INFO - Display Serial Number: 1600x1200 60 09:01:12 INFO - Main Display: Yes 09:01:12 INFO - Mirror: Off 09:01:12 INFO - Online: Yes 09:01:12 INFO - Rotation: Supported 09:01:12 INFO - Return code: 0 09:01:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:12 INFO - Python 2.7.3 09:01:12 INFO - Return code: 0 09:01:12 INFO - grabbing minidump binary from tooltool 09:01:12 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')]} 09:01:12 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 0x1090fad30>, '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 0x108f20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:12 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 09:01:12 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 09:01:12 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 09:01:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:13 INFO - Return code: 0 09:01:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 09:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --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 09:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvGnE4W6we/Render', 09:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:13 INFO - 'HOME': '/Users/cltbld', 09:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:13 INFO - 'LOGNAME': 'cltbld', 09:01:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:13 INFO - 'MOZ_NO_REMOTE': '1', 09:01:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:13 INFO - 'NO_EM_RESTART': '1', 09:01:13 INFO - 'PAGER': '/bin/cat', 09:01:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:13 INFO - 'PWD': '/builds/slave/test', 09:01:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:13 INFO - 'SHELL': '/bin/bash', 09:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SRUynctRvJ/Listeners', 09:01:13 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 09:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:13 INFO - 'USER': 'cltbld', 09:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:13 INFO - 'XPC_FLAGS': '0x0', 09:01:13 INFO - 'XPC_SERVICE_NAME': '0', 09:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 09:01:13 INFO - mozversion application_buildid: 20161014060236 09:01:13 INFO - mozversion application_changeset: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 09:01:13 INFO - mozversion application_display_name: Nightly 09:01:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:13 INFO - mozversion application_name: Firefox 09:01:13 INFO - mozversion application_remotingname: firefox 09:01:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:01:13 INFO - mozversion application_vendor: Mozilla 09:01:13 INFO - mozversion application_version: 52.0a1 09:01:13 INFO - mozversion platform_buildid: 20161014060236 09:01:13 INFO - mozversion platform_changeset: 0c899672fff6ae00f5b3affbec48ee4daac35fa1 09:01:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:01:13 INFO - mozversion platform_version: 52.0a1 09:01:13 INFO - using testdate: 1476460873 09:01:13 INFO - actual date: 1476460873 09:01:13 INFO - starting webserver on 'localhost:49272' 09:01:13 INFO - SUITE-START | Running 2 tests 09:01:13 INFO - TEST-START | dromaeo_css 09:01:13 INFO - operating with platform_type : mac_ 09:01:13 INFO - Initialising browser for dromaeo_css test... 09:01:13 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpHtJDyX/profile http://localhost:49272/getInfo.html) 09:01:15 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:01:15 INFO - PROCESS | 1656 | colorDepth:24 09:01:15 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:01:15 INFO - PROCESS | 1656 | __metrics 09:01:15 INFO - PROCESS | 1656 | 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" 09:01:15 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:16 INFO - TEST-INFO | 1656: exit 0 09:01:16 INFO - Browser initialized. 09:01:16 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:16 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpHtJDyX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:28 INFO - PROCESS | 1658 | RSS: Main: 232161280 09:01:28 INFO - PROCESS | 1658 | 09:02:19 INFO - PROCESS | 1658 | 1476460939572 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] 09:04:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:04:35 INFO - PROCESS | 1658 | RSS: Main: 421519360 09:04:35 INFO - PROCESS | 1658 | 09:07:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:07:39 INFO - PROCESS | 1658 | RSS: Main: 371662848 09:07:39 INFO - PROCESS | 1658 | 09:10:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:10:42 INFO - PROCESS | 1658 | RSS: Main: 341413888 09:10:42 INFO - PROCESS | 1658 | 09:13:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:13:45 INFO - PROCESS | 1658 | RSS: Main: 353075200 09:13:45 INFO - PROCESS | 1658 | 09:16:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:16:49 INFO - PROCESS | 1658 | RSS: Main: 335249408 09:16:49 INFO - PROCESS | 1658 | 09:19:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:19:53 INFO - PROCESS | 1658 | RSS: Main: 377294848 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | __start_tp_report 09:19:53 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:19:53 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:19:53 INFO - PROCESS | 1658 | |i|pagename|runs| 09:19:53 INFO - PROCESS | 1658 | |0;dojo.html;2834.291427143214;3323.53470514128;3346.3474622448616;3357.1607098225445;3401.6768407001327;5596.972015139929;5706.28671416073;5732.140178973156;5742.39704830993;5769.538436925046;5666.631668941526;5702.116171993337;5711.828645140642;5714.3999880012625;5716.22830917826;5178.456262092483;5222.712750798704;5230.2416149658275;5256.316678831747;5256.474352564751;5305.946940530604;5310.7610157543;5315.760790764405;5333.973330133344;5339.866503337409;5215.843524694265;5269.683818970855;5273.551748101416;5303.522682958533;5321.494458026501;5303.443138470444;5319.148936170213;5333.893322133536;5335.039692855285;5398.163284690879;5094.337736094284;5117.744112794346;5169.922451163236;5257.342832145981;5273.841784746463;5617.354004289518;5671.6597004179885;5711.857203569903;5731.971340143315;5741.33974592923;6244.937550624481;6275.497960163221;6278.529110316699;6287.654179020152;6300.5589608727405;6190.102435146879;6264.9686751566405;6291.716872740738;6356.713947872357;6360.363963603649;4828.830990915301;5790.594658373873;5797.971010144923;5805.187273781674;5809.738561764731;5334.759935802898;6262.2172228471445;6339.11252424661;6344.143540622049;6347.904781428282;5103.183490641553;6105.084237364431;6111.318268824403;6119.374694480196;6140.206206176179;6197.380261973766;6201.844953876099;6237.438630523275;6238.594491358047;6250.9062364064575;6224.782132625336;6228.688565571703;6232.844178895473;6252.343503932022;6262.6868656566985;5415.241866138744;6215.81352559424;6232.501399887998;6242.500599951993;6251.281102673251;4837.129316723023;4897.461279259279;4902;4915.729634870117;4924.655274130777;4342.174986752507;4394.2969124939855;4420.58004489573;4432.955670443319;4467.285234362552;5793;5814.244148260699;5816.621919575214;5817.185594016841;5836.358000619928;6144.003198960356;6324.462420694293;6338.778142764981;6341.207349081365;6352.8411789704705;5687.175359572839;5689.9146512802345;5704.201411802351;5723.227364305765;5769.68266745333;5395.514403703685;5405.5135037846785;5409.0804563223555;5411.458854114636;5440.510354068074;6293.213348331458;6304.338044505261;6308.684565771785;6319.304876463677;6319.778807741707;6111.052786818051;6191.226096737908;6205.937940620536;6256.906146407899;6295.213098362705;6236.656983865931;6251.562390632612;6287.497000240061;6289.088082228142;6313.210848643919;6212.1303017578475;6212.192414986023;6217.440430361289;6230.813075607739;6236.781712640036;6129.14485165794;6189.1025801257665;6266.373362663697;6295.055741638791;6309.1167236587835;5832.795852145155;6116;6125.571210015256;6172.537059720449;6188.257409110935;6025.337212906665;6135.877282454237;6153.261608606996;6156.722947467285;6165.84585385369;5709.714514274353;5962.880742385215;5991.430814072656;6001.06983417571;6003.7898673546215;5922.881542369042;5965.254343207099;5993.370696077023;5993.940060599338;6004.279486461653;5809.709514524172;5816.651000939957;5821.214136091573;5833.854153646193;5848.4151584842875 09:19:53 INFO - PROCESS | 1658 | |1;ext.html;461.5961034095167;474.4638558428975;498.8553319537333;531.195239212593;540.297613102966;30983.690163098363;40209.19581608366;41173.79413102934;41364.172716545654;41465.58534414655;43353.566464335345;43597.564024359745;43689;43741.7812910935;43834;9469.431834089943;9639.036096390375;9653.227741780676;9653.75865603362;9654.5172741363;9519.238460923145;9596.184324332406;9600;9615.903840961571;9617.615295388176;9560.904390956106;9628.277879159055;9630.229581633488;9642.517874106301;9642.566084526214;9548.952255238713;9602.759931001747;9636.373635713657;9636.71089867305;9640.807183856354;9492.857607135898;9568.186704130158;9571.425714457086;9582.185514231298;9583.472908990003;40492.19015619671;40886.59113408887;40894.38658420126;40910;40948.79525602383;3383.712384447325;3418.453047512386;3421.4012547804036;3425.657434256579;3446.5864096308605;30764.692353076407;31036.53445198324;31273.687263127304;31363.686363136305;31467.685323146703;3105;3217.1635374802486;3222.951655725166;3258.152880251128;3312.668733126692;459277;463245;463556;463586.9999999966;471402;56031.43968560344;58472.41527584755;62280;62866.68566657229;62867.37132628615;34425.139371515914;35241.47137792935;59689.10466343008;61495.69252153711;66110;16165.515034548982;16618;16621.584460388585;16657.33370665184;16663;10666.839997400046;12644.873551264369;12748.043896707784;12769.61691149267;12771.361431928366;19432.500435963342;19483.14092811924;19490.74557672904;19523.13103434552;19537.80569890066;6322.936770632326;7048.682809273595;7851.332636725942;7977.800554986172;8013.919860801434;857.3484152044491;938.2165891480554;975.2831668723483;983.3952119446632;1033.4729288063143;22974.540509189723;25134.371640709127;30562.624681889367;30648;31254.218644533616;1051.30613794895;1059.6079450603327;1072.860528131338;1075.9408232547285;1082.1126676125502;15848.76226856598;16002.062479238708;16144.75782863258;16170.67658646844;16252.51242462728;660773.6961315259;664014;664265;664925;667202;58482;58970.70514647399;59159.70420147958;59193.40806591879;59247;1151.740858306891;1245.7508498300194;1278.8849003589721;1389.9235542045285;1405.669667628086;28387.574186386806;30773.692263077082;31390;32393.35213295768;32446.35107297888;51883.481165189376;56505;58364.708176458844;58531.41468585259;61163.69418153059;489836;610609;610859;611293;612388;34945.65054349424;35185.824070879484;35351.93944181679;36477.45283820692;42798.78600606977;45000.54999449963;46821.82945426391;47193.528064718914;47525;48372.274415884494;37868.810655946545;39531.80234098811;39875.80062099787;40663.39004914869;40973.38539921963;34293.314133717686;37935;39190.608093918694;39272.60727392689;40744.59255407408;38424.20441139221;38912.80543597264;39093.609063908996;39239;39261;32125.839370803933;33893;34712.65287347094;34901.30197395987;35007.949761507196;23258.138349442244;25033.87483062573;25383;25480.490390192463;26170 09:19:53 INFO - PROCESS | 1658 | |2;jquery.html;371.5559905912434;376.55754488476043;379.8214839025659;395.3338624417858;404.842111576485;14170.63001008852;15439.382424702999;18538;20797.99999999998;21040.473988150305;20091.29680461184;20330.898345508253;21037.68443473349;21379.358619241448;21466.277418967802;5325.174597937326;5522.66863988162;5527.944720552783;5531.944680553183;5538.363088244843;3903.941440878389;4119.814608342616;5459.454054594548;5821.243238379005;6038.5471089668235;6092.72582733778;6146.539009574278;6149.6617686027475;6161.845953851145;6163.691815409234;5827.242458480391;6030.396960303979;6071.392860713893;6071.787487437962;6085.361037091086;5226;5715.342735585419;5866.823995280149;5874.441928016831;5880.5883588148845;13483.662908427368;13643.181409115385;17814.020228887406;25011.37471563207;25332;2303.8272129590346;2422.5760491913843;2534.6831646044243;2690.825096368749;2957.6155099837097;9863.753406164831;10460.215483838743;10482.842757358645;10543.683689489328;10567.996040376152;1823.1157888424236;1883.1055248756868;1922.2311075569744;1936.3513223070288;1971.1129991503738;102468.48765756123;108894.3665845013;111453;118467;118514.62970740623;11284.035024589155;11301.604443844426;11484.712882177846;11490.655280341603;11635.080211765133;14529.273536323353;14531.564053078424;14589.781153282709;14592.781108283385;14600.634984125269;8349.54077525742;8508.106648801786;8510.829783404297;8519.659213631385;8662.956685216534;8327.458715183493;8363.121872203452;8502.149785021573;8542.572871356408;8547.786305342415;3691.9630803692157;4420.160169567836;4506.594406503365;4590.380298659705;4603.424571928509;1559.7738327942384;1859.3492277702967;1921.8846869187894;1927.759030121235;1930.2086144680613;862.9956850215834;923.2798417234568;978.3319440354179;978.554757585297;983.0759086458706;20996.950152492314;21566.676499852514;21587.568248635253;21637.459063523536;21659.350219493124;874.9343799215085;904.1862323908535;904.4844438669895;922.6908985489936;962.5668449197889;8665.870011949826;10659.360438373722;11209.831852522218;11397.487113079931;11422.428878556038;460033;465788;468405;541682;542979;128931.35534322268;129299;130791;135623;138176;935.4948327902993;974.7319487141093;991.177322822056;991.620111731835;993.4072071892355;19216.423507294803;19437.820868513336;19700.80299197047;19789.505262368555;19856.90071549633;43155;47082.76458617685;51129.74435127801;56275;57556;62987;80778.59610702144;80820.81384936775;82379;82896.58551707445;6977.162740471176;7920.445568810128;7959.920400795918;8059;8234.752957411287;7274.8908766367485;9870.457124858201;10770.246082774443;12848.310366716269;13258.668533286746;8951.641934322553;10873.728156796144;11152.832707509231;11295.661130166109;11605.6518304451;11082.279651822606;11332.093432525544;11410.828837567276;11507.079433645122;11771.352575608424;10364.948175259075;10858.34849909008;11376;11405.885941140483;11633.302001879914;7327.670254838438;7436.516626419266;7455.254474552501;7658.617069146632;7699.846003080019;9460.621575137193;10913.126949844152;11062.668119956414;11072.778544428906;11211.383373914514 09:19:53 INFO - PROCESS | 1658 | |3;mootools.html;1362.0465674028183;1532.1843615315847;1566.0055864526025;1773.538879891228;1915.750952376191;7790.6104694765245;8256.380771442136;8466.872996905044;8791.252743516792;8825.293976481882;8626.396152269344;8731.083236260181;8744.737657870273;8746.431481953668;8785.824283514326;1980.87124336918;2208.392692009694;2382.332946774906;2382.6902502674716;2384.403899025244;1951.4048215294329;2370.608849539824;2386.773256540626;2390.916317928873;2392.6411038344213;2098.8845613491253;2282.9840720879215;2348.377679914824;2368.763123687626;2379.357573455166;2325.5348930213854;2336.836421450499;2346.2609278077352;2347.4014126397747;2349.1308215960203;2332.090484710964;2337.777911098443;2338.018032426385;2339.4619237575284;2342.5314937012668;8518.105598912087;8760.956195218983;8793.296536277083;8884.777880553038;8889.511076890769;5207.114790485626;5366.517013468768;5369.865753356158;5373.516383525502;5389.003034438641;3585.713142948558;3601.603823579404;3665.725070619714;3673.081729567608;3674.761140525857;1049.6536143072844;1058.0847566854693;1064.3879769132702;1072.8068947589431;1084.772197838447;10579.68260952165;10629.574817007311;11302.265352752127;11367.431628418628;11383;4351;4403.581659742319;4586.472555656075;4594.632429405639;4596.931046034312;9035.051319611346;9037.412568183046;9040.186383225542;9045.638174473079;9050.366474346733;5233.659812112277;5235;5244.449332819999;5247.055530004636;5257.08001099806;4090.7750073745633;4372.0818628088455;4546.04533048057;4890.168671325883;4905.386826646669;3314.9557889264806;3493.336266109431;3693.316736403774;3782.735208535431;3798.6771124454176;1143.8398624192619;1164.6622479481025;1165.0621249893748;1176.3236139219982;1177.8115501519887;798.5448294472095;889.6797153024905;908.3096846396786;917.454114801692;956.4452617481844;5256.237845512379;5694.857628559236;5979.222701048923;6033.4871535919065;6126.356732543019;392.72509243529646;430.84489583749826;445.69469913108975;447.96192323652855;452.7804015052697;3328.766986310935;3956.1098752780395;3965.7422267552515;4085.8774236772942;4087.693422993287;10279.280450368402;10565.524551395205;10681.252312338062;10683;10688.465576721133;10647;10662.41356725372;10668.946655266674;10672.946635266773;10683.145348372267;7841.372690184771;8101.392395570356;8226.753197404087;8239.711610093618;8242.258196762321;5734.827955161351;5837.211976383303;5842.883142337045;5859.326177489644;5914.142449344907;9871.654492092743;9901.455419951684;9932.553035113582;10008.199344052313;10038.899611004088;9140.542972851197;9165.220956218796;10024.799504009734;10037.34757240777;10115.089641931967;6946.270641582561;7160.785176444507;7269.273072692688;7379.594122323324;7487.625618718933;8140.267375936194;8148.470349427268;8152.796180095545;8230.547319897463;8237.723152911309;7357.668904899399;7382.040334756447;7434.665440055102;7445.10658720957;7494.325510704063;7156.856862862818;7350.338469537767;7402.074740657418;7414.073240844895;7448;6892.310768923067;6927.480438966997;6982.581045137308;7061.858762824818;7064.964675176591;4863.392075990501;4934.136526107845;4949.455559888482;5005.724685142352;5053.36832895888;4985.227289770091;5021.07110184617;5059.696418214919;5111.028904508132;5176.2494438306985 09:19:53 INFO - PROCESS | 1658 | |4;prototype.html;298.31387808041507;300.6993006993007;307.3100888505305;314.3242029636282;316.2331346484774;4671.626269898408;4886.827161481246;5129.281900533923;5181.5854731621475;5201.999999999995;4875.780589873455;4954.950450495494;5087.694738315704;5114.565261952729;5211.661242019266;3450.516927630134;3927.7643341399466;3990.64084232419;4005.6995725320717;4018.8995275118136;3471.63547827477;3952.430919902058;3954.536821376095;3965.03730653327;3983.9601603984024;3821.312163810513;3974.721769476138;3979.4030895365636;3984.3226651469176;3995.900102497432;3433.725301975836;3958.9010274743073;3984.143409167043;3992.141689536749;4042.191561687645;3504.737144714157;3509.8242088900247;3511.964880351215;3521.8767343142868;3528.700060494861;4890.291479263488;5060.418051924039;5074.67014644047;5082.720450375228;5091.065395149856;3942;4438.7114837535455;4518.615917647004;4518.751468669222;4534.322300748744;4198.580141985807;4954.777035033396;5237.004969055867;5261.894762104736;5417.377001644822;3583.94624080639;3781.7920014399197;3806.2577797329254;3807.58116607173;3827.311084004878;4650.697704649187;5015.423226328946;5449.964506743706;5710.71446427677;5710.972025035492;3208.8235147066666;3362.9663703363144;3859.7298189127055;3869.767813931117;3906.4921560196995;3104.764569509949;3292.234523000691;3666.9365883893374;3927.7054220933546;4000.439938408625;4189.560096189917;4464.620507256855;4531.932021019687;4552.7495987720995;4556.794944227518;5487.338640759229;5487.835364939058;5538.867535998669;5549.223108764776;5612.382637909826;1064.9989010330419;1302.4790083966489;1324.675454513631;1325.9801102983463;1351.1622793867668;752.712861007672;828.188375392119;857.3355649371662;857.9528125953008;870.4385671242013;3880.553736320303;4218.873433796991;4913.140200464904;5012.69669885825;5091.134507133796;4482.955170448319;5031.320771695774;5552.361478429952;5616.315105468425;5628.352739435018;4200.915981680349;4397.559354558187;4762.380890484214;4810.278458231293;4860.902781944341;2318;2861.7996740227986;2892.3203047283873;2902.912912612625;2915.454808951259;4670.953290467051;4954.8761280968265;5160.561352285098;5708.372079071299;5763.827085187451;4690.390249267573;5161.354830646169;5613.915791263134;5635.5773317001385;5684.744186511617;4904.534069263485;5407.891842163213;5606.299212598426;5649.491545760901;5704.401037891044;5069.108449009358;5467.289252397322;5511.063119269654;5514.105094825249;5519.806806761744;4510.548945105464;4715.316279139437;5392.245085687929;5414.29614150158;5418.126039881504;4932.926006109983;5139.537441630271;5191.402988656353;5649.943500564942;5767.653940763567;3743.700503959731;3748.8687895923513;3749.5500539935383;3765.604611515861;3766.227923275777;4314.309710446313;4529.524399938091;5257.658252213594;5431.972840135774;5442.183672449164;5314.229436731575;5337.2513155724055;5341.919871201857;5356;5411.918821217606;4712.340272361804;5213.552501782477;5373.167159090347;5374.4625537445945;5394.973025134849;4375.562443755599;4728.929066063943;4742.241241401359;4744.596709279612;4842.661013729005;4209.452771139732;4330.26385514457;4679.040796636749;5318.308619879391;5358.866028349323;4566.566176213254;4671.929921051119;5059.382541482857;5295.311609490742;5332.093544097435 09:19:53 INFO - PROCESS | 1658 | |5;yui.html;679.5277282288808;791.1574173505217;796.0526972902246;818.7339114787695;828.9336853051757;15259.160746158957;15344.693106137871;15891.44379946702;16231.675366492664;16418.50744477666;14306.856931430682;14713.190774507399;14716.852831471682;14717.632059198526;14721.043132196426;699.3985172751429;869.5000374784493;893.8614514750192;894.2890402130322;894.838928992781;718.392957950531;875.0549406640845;875.3522393428881;879.1164879296298;882.2633101360371;954.3510412919212;955.1833182628857;955.2405837359316;955.5652178258875;957.1003256938491;949.5774380400725;949.9050094990515;951.0774548687762;951.4148798488949;951.8524628682566;537.9596530260206;556.4324389123071;676.1953275602018;695.7947405515334;701.5790525684548;15442.227888605525;15508.767368489369;15511.5346539604;15530.301136448887;15606.765898511418;13726.45094196231;14068.859311406857;14176.078554893898;14177;14395.78406323906;11325.037371823404;12376.876231237753;12707.682307942283;12733.681657958625;12748.490060397573;4416.094700586372;4538.591526762574;4546.227141385973;4555.293929440942;4579.3131030345385;90861.09138908659;112829.30756038747;118272.40863795627;118275.4086229572;118687.62624747543;10885.292455990335;11133.276337038067;11151.386673733023;11437.828432573519;11515.366654834064;22350.329490115324;22407.77592224057;22497.212597559006;22512.887435563043;22559.097636094702;7759.57322347265;7947.841043179104;7987.9201207988335;8031.277185053374;8033.3974951878845;4217.34631132175;4344.044310251739;4371.732197662643;4414.889627759268;4501.347304640809;3469.965300347015;3496.423090190141;3594.1913069559664;3600.279944011208;3600.405933021022;650.7852408705116;654.5011922697065;656.4354654996699;670.1589505170979;673.6429692263108;528.7224207291202;555.4528789142691;579.2874764040254;610.7282259394616;631.2298995225817;8441.70454034106;8826.823463530693;8852.291816654651;8940.240079593308;9103.499307538017;586.973586188614;603.7253049862302;636.4367534731796;682.4885961726887;697.9616121113286;4373.497047839475;4403.911921761547;4621.792019359137;4922.040202160544;5021.5250926775025;95924.04075959291;120830;137495;143922;164322;82986;106905;112086.31870521948;121285.39357303157;135271;73983.15042123882;100314.49528257082;106210;110487;113354.43322783333;9780.804383912424;9935.254855885692;9954.054364835329;10023.248256380946;10068.748281293027;40196.79409617716;56844.71577642085;57321.713391434445;57698;60487.69756151191;70253.94619080615;74682;87723;90882.54558727164;92492.53753731189;13059.766293696035;13510.054296199172;13535.932320338336;13921.79117313221;13958;15005.774913376526;15155.545333640008;15385.384584616937;15621.062736235863;15731.528054158394;14074;14382.496612618508;14414.834510675268;14427.711445771236;14758.704825903636;11934.880651193376;13837.446502140205;13890.791638125638;14681.192534410282;14707.92646036763;13070.477180913038;13098.476060957455;13460.865391345962;13732.480392499136;13872.098313609988;11336.829947550628;11585.7103572408;11707.590234341757;11741.823872641746;11861;10911.508982095665;11194.525710318772;11515.53937842477;11541.422928853355;11556.88443115558 09:19:53 INFO - PROCESS | 1658 | __end_tp_report 09:19:53 INFO - PROCESS | 1658 | __start_cc_report 09:19:53 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1975 09:19:53 INFO - PROCESS | 1658 | __end_cc_report 09:19:53 INFO - PROCESS | 1658 | __startTimestamp1476461992995__endTimestamp 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:19:53 INFO - PROCESS | 1658 | Number of tests: 6 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5733.88 Median:5833.85 stddev:653.10 (11.2%) stddev-sans-first:614.22 09:19:53 INFO - PROCESS | 1658 | Values: 2834.3 3323.5 3346.3 3357.2 3401.7 5597.0 5706.3 5732.1 5742.4 5769.5 5666.6 5702.1 5711.8 5714.4 5716.2 5178.5 5222.7 5230.2 5256.3 5256.5 5305.9 5310.8 5315.8 5334.0 5339.9 5215.8 5269.7 5273.6 5303.5 5321.5 5303.4 5319.1 5333.9 5335.0 5398.2 5094.3 5117.7 5169.9 5257.3 5273.8 5617.4 5671.7 5711.9 5732.0 5741.3 6244.9 6275.5 6278.5 6287.7 6300.6 6190.1 6265.0 6291.7 6356.7 6360.4 4828.8 5790.6 5798.0 5805.2 5809.7 5334.8 6262.2 6339.1 6344.1 6347.9 5103.2 6105.1 6111.3 6119.4 6140.2 6197.4 6201.8 6237.4 6238.6 6250.9 6224.8 6228.7 6232.8 6252.3 6262.7 5415.2 6215.8 6232.5 6242.5 6251.3 4837.1 4897.5 4902.0 4915.7 4924.7 4342.2 4394.3 4420.6 4433.0 4467.3 5793.0 5814.2 5816.6 5817.2 5836.4 6144.0 6324.5 6338.8 6341.2 6352.8 5687.2 5689.9 5704.2 5723.2 5769.7 5395.5 5405.5 5409.1 5411.5 5440.5 6293.2 6304.3 6308.7 6319.3 6319.8 6111.1 6191.2 6205.9 6256.9 6295.2 6236.7 6251.6 6287.5 6289.1 6313.2 6212.1 6212.2 6217.4 6230.8 6236.8 6129.1 6189.1 6266.4 6295.1 6309.1 5832.8 6116.0 6125.6 6172.5 6188.3 6025.3 6135.9 6153.3 6156.7 6165.8 5709.7 5962.9 5991.4 6001.1 6003.8 5922.9 5965.3 5993.4 5993.9 6004.3 5809.7 5816.7 5821.2 5833.9 5848.4 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:70779.33 Median:40902.19 stddev:154629.20 (378.0%) stddev-sans-first:154972.85 09:19:53 INFO - PROCESS | 1658 | Values: 461.6 474.5 498.9 531.2 540.3 30983.7 40209.2 41173.8 41364.2 41465.6 43353.6 43597.6 43689.0 43741.8 43834.0 9469.4 9639.0 9653.2 9653.8 9654.5 9519.2 9596.2 9600.0 9615.9 9617.6 9560.9 9628.3 9630.2 9642.5 9642.6 9549.0 9602.8 9636.4 9636.7 9640.8 9492.9 9568.2 9571.4 9582.2 9583.5 40492.2 40886.6 40894.4 40910.0 40948.8 3383.7 3418.5 3421.4 3425.7 3446.6 30764.7 31036.5 31273.7 31363.7 31467.7 3105.0 3217.2 3223.0 3258.2 3312.7 459277.0 463245.0 463556.0 463587.0 471402.0 56031.4 58472.4 62280.0 62866.7 62867.4 34425.1 35241.5 59689.1 61495.7 66110.0 16165.5 16618.0 16621.6 16657.3 16663.0 10666.8 12644.9 12748.0 12769.6 12771.4 19432.5 19483.1 19490.7 19523.1 19537.8 6322.9 7048.7 7851.3 7977.8 8013.9 857.3 938.2 975.3 983.4 1033.5 22974.5 25134.4 30562.6 30648.0 31254.2 1051.3 1059.6 1072.9 1075.9 1082.1 15848.8 16002.1 16144.8 16170.7 16252.5 660773.7 664014.0 664265.0 664925.0 667202.0 58482.0 58970.7 59159.7 59193.4 59247.0 1151.7 1245.8 1278.9 1389.9 1405.7 28387.6 30773.7 31390.0 32393.4 32446.4 51883.5 56505.0 58364.7 58531.4 61163.7 489836.0 610609.0 610859.0 611293.0 612388.0 34945.7 35185.8 35351.9 36477.5 42798.8 45000.5 46821.8 47193.5 47525.0 48372.3 37868.8 39531.8 39875.8 40663.4 40973.4 34293.3 37935.0 39190.6 39272.6 40744.6 38424.2 38912.8 39093.6 39239.0 39261.0 32125.8 33893.0 34712.7 34901.3 35007.9 23258.1 25033.9 25383.0 25480.5 26170.0 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:31803.07 Median:374.06 stddev:84132.98 (22492.0%) stddev-sans-first:84335.89 09:19:53 INFO - PROCESS | 1658 | Values: 371.6 376.6 379.8 395.3 404.8 14170.6 15439.4 18538.0 20798.0 21040.5 20091.3 20330.9 21037.7 21379.4 21466.3 5325.2 5522.7 5527.9 5531.9 5538.4 3903.9 4119.8 5459.5 5821.2 6038.5 6092.7 6146.5 6149.7 6161.8 6163.7 5827.2 6030.4 6071.4 6071.8 6085.4 5226.0 5715.3 5866.8 5874.4 5880.6 13483.7 13643.2 17814.0 25011.4 25332.0 2303.8 2422.6 2534.7 2690.8 2957.6 9863.8 10460.2 10482.8 10543.7 10568.0 1823.1 1883.1 1922.2 1936.4 1971.1 102468.5 108894.4 111453.0 118467.0 118514.6 11284.0 11301.6 11484.7 11490.7 11635.1 14529.3 14531.6 14589.8 14592.8 14600.6 8349.5 8508.1 8510.8 8519.7 8663.0 8327.5 8363.1 8502.1 8542.6 8547.8 3692.0 4420.2 4506.6 4590.4 4603.4 1559.8 1859.3 1921.9 1927.8 1930.2 863.0 923.3 978.3 978.6 983.1 20997.0 21566.7 21587.6 21637.5 21659.4 874.9 904.2 904.5 922.7 962.6 8665.9 10659.4 11209.8 11397.5 11422.4 460033.0 465788.0 468405.0 541682.0 542979.0 128931.4 129299.0 130791.0 135623.0 138176.0 935.5 974.7 991.2 991.6 993.4 19216.4 19437.8 19700.8 19789.5 19856.9 43155.0 47082.8 51129.7 56275.0 57556.0 62987.0 80778.6 80820.8 82379.0 82896.6 6977.2 7920.4 7959.9 8059.0 8234.8 7274.9 9870.5 10770.2 12848.3 13258.7 8951.6 10873.7 11152.8 11295.7 11605.7 11082.3 11332.1 11410.8 11507.1 11771.4 10364.9 10858.3 11376.0 11405.9 11633.3 7327.7 7436.5 7455.3 7658.6 7699.8 9460.6 10913.1 11062.7 11072.8 11211.4 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5595.71 Median:4919.76 stddev:3143.24 (63.9%) stddev-sans-first:3135.95 09:19:53 INFO - PROCESS | 1658 | Values: 1362.0 1532.2 1566.0 1773.5 1915.8 7790.6 8256.4 8466.9 8791.3 8825.3 8626.4 8731.1 8744.7 8746.4 8785.8 1980.9 2208.4 2382.3 2382.7 2384.4 1951.4 2370.6 2386.8 2390.9 2392.6 2098.9 2283.0 2348.4 2368.8 2379.4 2325.5 2336.8 2346.3 2347.4 2349.1 2332.1 2337.8 2338.0 2339.5 2342.5 8518.1 8761.0 8793.3 8884.8 8889.5 5207.1 5366.5 5369.9 5373.5 5389.0 3585.7 3601.6 3665.7 3673.1 3674.8 1049.7 1058.1 1064.4 1072.8 1084.8 10579.7 10629.6 11302.3 11367.4 11383.0 4351.0 4403.6 4586.5 4594.6 4596.9 9035.1 9037.4 9040.2 9045.6 9050.4 5233.7 5235.0 5244.4 5247.1 5257.1 4090.8 4372.1 4546.0 4890.2 4905.4 3315.0 3493.3 3693.3 3782.7 3798.7 1143.8 1164.7 1165.1 1176.3 1177.8 798.5 889.7 908.3 917.5 956.4 5256.2 5694.9 5979.2 6033.5 6126.4 392.7 430.8 445.7 448.0 452.8 3328.8 3956.1 3965.7 4085.9 4087.7 10279.3 10565.5 10681.3 10683.0 10688.5 10647.0 10662.4 10668.9 10672.9 10683.1 7841.4 8101.4 8226.8 8239.7 8242.3 5734.8 5837.2 5842.9 5859.3 5914.1 9871.7 9901.5 9932.6 10008.2 10038.9 9140.5 9165.2 10024.8 10037.3 10115.1 6946.3 7160.8 7269.3 7379.6 7487.6 8140.3 8148.5 8152.8 8230.5 8237.7 7357.7 7382.0 7434.7 7445.1 7494.3 7156.9 7350.3 7402.1 7414.1 7448.0 6892.3 6927.5 6982.6 7061.9 7065.0 4863.4 4934.1 4949.5 5005.7 5053.4 4985.2 5021.1 5059.7 5111.0 5176.2 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4312.81 Median:4722.12 stddev:1298.87 (27.5%) stddev-sans-first:1267.08 09:19:53 INFO - PROCESS | 1658 | Values: 298.3 300.7 307.3 314.3 316.2 4671.6 4886.8 5129.3 5181.6 5202.0 4875.8 4955.0 5087.7 5114.6 5211.7 3450.5 3927.8 3990.6 4005.7 4018.9 3471.6 3952.4 3954.5 3965.0 3984.0 3821.3 3974.7 3979.4 3984.3 3995.9 3433.7 3958.9 3984.1 3992.1 4042.2 3504.7 3509.8 3512.0 3521.9 3528.7 4890.3 5060.4 5074.7 5082.7 5091.1 3942.0 4438.7 4518.6 4518.8 4534.3 4198.6 4954.8 5237.0 5261.9 5417.4 3583.9 3781.8 3806.3 3807.6 3827.3 4650.7 5015.4 5450.0 5710.7 5711.0 3208.8 3363.0 3859.7 3869.8 3906.5 3104.8 3292.2 3666.9 3927.7 4000.4 4189.6 4464.6 4531.9 4552.7 4556.8 5487.3 5487.8 5538.9 5549.2 5612.4 1065.0 1302.5 1324.7 1326.0 1351.2 752.7 828.2 857.3 858.0 870.4 3880.6 4218.9 4913.1 5012.7 5091.1 4483.0 5031.3 5552.4 5616.3 5628.4 4200.9 4397.6 4762.4 4810.3 4860.9 2318.0 2861.8 2892.3 2902.9 2915.5 4671.0 4954.9 5160.6 5708.4 5763.8 4690.4 5161.4 5613.9 5635.6 5684.7 4904.5 5407.9 5606.3 5649.5 5704.4 5069.1 5467.3 5511.1 5514.1 5519.8 4510.5 4715.3 5392.2 5414.3 5418.1 4932.9 5139.5 5191.4 5649.9 5767.7 3743.7 3748.9 3749.6 3765.6 3766.2 4314.3 4529.5 5257.7 5432.0 5442.2 5314.2 5337.3 5341.9 5356.0 5411.9 4712.3 5213.6 5373.2 5374.5 5395.0 4375.6 4728.9 4742.2 4744.6 4842.7 4209.5 4330.3 4679.0 5318.3 5358.9 4566.6 4671.9 5059.4 5295.3 5332.1 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:23475.91 Median:3597.24 stddev:36352.78 (1010.6%) stddev-sans-first:36414.46 09:19:53 INFO - PROCESS | 1658 | Values: 679.5 791.2 796.1 818.7 828.9 15259.2 15344.7 15891.4 16231.7 16418.5 14306.9 14713.2 14716.9 14717.6 14721.0 699.4 869.5 893.9 894.3 894.8 718.4 875.1 875.4 879.1 882.3 954.4 955.2 955.2 955.6 957.1 949.6 949.9 951.1 951.4 951.9 538.0 556.4 676.2 695.8 701.6 15442.2 15508.8 15511.5 15530.3 15606.8 13726.5 14068.9 14176.1 14177.0 14395.8 11325.0 12376.9 12707.7 12733.7 12748.5 4416.1 4538.6 4546.2 4555.3 4579.3 90861.1 112829.3 118272.4 118275.4 118687.6 10885.3 11133.3 11151.4 11437.8 11515.4 22350.3 22407.8 22497.2 22512.9 22559.1 7759.6 7947.8 7987.9 8031.3 8033.4 4217.3 4344.0 4371.7 4414.9 4501.3 3470.0 3496.4 3594.2 3600.3 3600.4 650.8 654.5 656.4 670.2 673.6 528.7 555.5 579.3 610.7 631.2 8441.7 8826.8 8852.3 8940.2 9103.5 587.0 603.7 636.4 682.5 698.0 4373.5 4403.9 4621.8 4922.0 5021.5 95924.0 120830.0 137495.0 143922.0 164322.0 82986.0 106905.0 112086.3 121285.4 135271.0 73983.2 100314.5 106210.0 110487.0 113354.4 9780.8 9935.3 9954.1 10023.2 10068.7 40196.8 56844.7 57321.7 57698.0 60487.7 70253.9 74682.0 87723.0 90882.5 92492.5 13059.8 13510.1 13535.9 13921.8 13958.0 15005.8 15155.5 15385.4 15621.1 15731.5 14074.0 14382.5 14414.8 14427.7 14758.7 11934.9 13837.4 13890.8 14681.2 14707.9 13070.5 13098.5 13460.9 13732.5 13872.1 11336.8 11585.7 11707.6 11741.8 11861.0 10911.5 11194.5 11515.5 11541.4 11556.9 09:19:53 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:19:53 INFO - PROCESS | 1658 | 09:19:53 INFO - TEST-INFO | 1658: exit 0 09:19:53 INFO - TEST-OK | dromaeo_css | took 1120326ms 09:19:53 INFO - TEST-START | kraken 09:19:53 INFO - operating with platform_type : mac_ 09:19:53 INFO - Initialising browser for kraken test... 09:19:53 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpaTZXnz/profile http://localhost:49272/getInfo.html) 09:19:55 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 09:19:55 INFO - PROCESS | 1667 | colorDepth:24 09:19:55 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 09:19:55 INFO - PROCESS | 1667 | __metrics 09:19:56 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:19:56 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:56 INFO - TEST-INFO | 1667: exit 0 09:19:56 INFO - Browser initialized. 09:19:56 INFO - Running cycle 1/1 for kraken test... 09:19:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpaTZXnz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:08 INFO - PROCESS | 1668 | RSS: Main: 232120320 09:20:08 INFO - PROCESS | 1668 | 09:20:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:12 INFO - PROCESS | 1668 | RSS: Main: 290365440 09:20:12 INFO - PROCESS | 1668 | 09:20:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:20:15 INFO - PROCESS | 1668 | RSS: Main: 291250176 09:20:15 INFO - PROCESS | 1668 | 09:20:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:20:19 INFO - PROCESS | 1668 | RSS: Main: 386060288 09:20:19 INFO - PROCESS | 1668 | 09:20:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:20:22 INFO - PROCESS | 1668 | RSS: Main: 286015488 09:20:22 INFO - PROCESS | 1668 | 09:20:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:24 INFO - PROCESS | 1668 | RSS: Main: 291364864 09:20:24 INFO - PROCESS | 1668 | 09:20:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:27 INFO - PROCESS | 1668 | RSS: Main: 329850880 09:20:27 INFO - PROCESS | 1668 | 09:20:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:31 INFO - PROCESS | 1668 | RSS: Main: 330067968 09:20:31 INFO - PROCESS | 1668 | 09:20:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:20:34 INFO - PROCESS | 1668 | RSS: Main: 328663040 09:20:34 INFO - PROCESS | 1668 | 09:20:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:36 INFO - PROCESS | 1668 | RSS: Main: 302739456 09:20:36 INFO - PROCESS | 1668 | 09:20:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:39 INFO - PROCESS | 1668 | RSS: Main: 304914432 09:20:39 INFO - PROCESS | 1668 | 09:20:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:42 INFO - PROCESS | 1668 | RSS: Main: 306794496 09:20:42 INFO - PROCESS | 1668 | 09:20:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:46 INFO - PROCESS | 1668 | RSS: Main: 315535360 09:20:46 INFO - PROCESS | 1668 | 09:20:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:49 INFO - PROCESS | 1668 | RSS: Main: 314937344 09:20:49 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:20:51 INFO - PROCESS | 1668 | RSS: Main: 359116800 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | __start_tp_report 09:20:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 09:20:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 09:20:51 INFO - PROCESS | 1668 | |i|pagename|runs| 09:20:51 INFO - PROCESS | 1668 | |0;ai-astar;103;114;114;114;114;112;112;111;112;112 09:20:51 INFO - PROCESS | 1668 | |1;audio-beat-detection;149;114;114;148;115;113;112;114;145;111 09:20:51 INFO - PROCESS | 1668 | |2;audio-dft;183;181;183;185;185;183;182;181;181;182 09:20:51 INFO - PROCESS | 1668 | |3;audio-fft;80;59;59;87;64;62;59;58;83;57 09:20:51 INFO - PROCESS | 1668 | |4;audio-oscillator;86;69;91;69;68;70;92;67;68;90 09:20:51 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;85;88;84;87;84;88;85;87;83 09:20:51 INFO - PROCESS | 1668 | |6;imaging-darkroom;94;93;91;93;93;93;93;93;111;149 09:20:51 INFO - PROCESS | 1668 | |7;imaging-desaturate;84;83;83;81;82;82;85;86;82;84 09:20:51 INFO - PROCESS | 1668 | |8;json-parse-financial;53;55;52;53;54;54;54;55;54;54 09:20:51 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;71;68;70;74;70;69;68;68;70;71 09:20:51 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;90;86;83;85;88;88;103;109;75 09:20:51 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;148;145;143;143;144;147;146;143;207;152 09:20:51 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;170;170;170;170;168;169;174;170;168;170 09:20:51 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;69;69;70;69;69;68;67;68;66 09:20:51 INFO - PROCESS | 1668 | __end_tp_report 09:20:51 INFO - PROCESS | 1668 | __start_cc_report 09:20:51 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1979 09:20:51 INFO - PROCESS | 1668 | __end_cc_report 09:20:51 INFO - PROCESS | 1668 | __startTimestamp1476462051446__endTimestamp 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | ------- Summary: start ------- 09:20:51 INFO - PROCESS | 1668 | Number of tests: 14 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.29 (2.9%) stddev-sans-first:1.20 09:20:51 INFO - PROCESS | 1668 | Values: 103.0 114.0 114.0 114.0 114.0 112.0 112.0 111.0 112.0 112.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:114.50 stddev:16.51 (14.4%) stddev-sans-first:14.71 09:20:51 INFO - PROCESS | 1668 | Values: 149.0 114.0 114.0 148.0 115.0 113.0 112.0 114.0 145.0 111.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.51 (0.8%) stddev-sans-first:1.59 09:20:51 INFO - PROCESS | 1668 | Values: 183.0 181.0 183.0 185.0 185.0 183.0 182.0 181.0 181.0 182.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.80 Median:63.00 stddev:11.70 (18.6%) stddev-sans-first:11.39 09:20:51 INFO - PROCESS | 1668 | Values: 80.0 59.0 59.0 87.0 64.0 62.0 59.0 58.0 83.0 57.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.00 stddev:11.11 (14.2%) stddev-sans-first:11.29 09:20:51 INFO - PROCESS | 1668 | Values: 86.0 69.0 91.0 69.0 68.0 70.0 92.0 67.0 68.0 90.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 09:20:51 INFO - PROCESS | 1668 | Values: 87.0 85.0 88.0 84.0 87.0 84.0 88.0 85.0 87.0 83.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:93.00 stddev:18.05 (19.4%) stddev-sans-first:19.00 09:20:51 INFO - PROCESS | 1668 | Values: 94.0 93.0 91.0 93.0 93.0 93.0 93.0 93.0 111.0 149.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:83.20 Median:83.50 stddev:1.55 (1.9%) stddev-sans-first:1.62 09:20:51 INFO - PROCESS | 1668 | Values: 84.0 83.0 83.0 81.0 82.0 82.0 85.0 86.0 82.0 84.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 09:20:51 INFO - PROCESS | 1668 | Values: 53.0 55.0 52.0 53.0 54.0 54.0 54.0 55.0 54.0 54.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:1.85 (2.6%) stddev-sans-first:1.92 09:20:51 INFO - PROCESS | 1668 | Values: 71.0 68.0 70.0 74.0 70.0 69.0 68.0 68.0 70.0 71.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:86.50 stddev:9.77 (11.3%) stddev-sans-first:10.32 09:20:51 INFO - PROCESS | 1668 | Values: 87.0 90.0 86.0 83.0 85.0 88.0 88.0 103.0 109.0 75.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:146.50 stddev:19.60 (13.4%) stddev-sans-first:20.74 09:20:51 INFO - PROCESS | 1668 | Values: 148.0 145.0 143.0 143.0 144.0 147.0 146.0 143.0 207.0 152.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:170.00 stddev:1.66 (1.0%) stddev-sans-first:1.76 09:20:51 INFO - PROCESS | 1668 | Values: 170.0 170.0 170.0 170.0 168.0 169.0 174.0 170.0 168.0 170.0 09:20:51 INFO - PROCESS | 1668 | 09:20:51 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 09:20:51 INFO - PROCESS | 1668 | Values: 68.0 69.0 69.0 70.0 69.0 69.0 68.0 67.0 68.0 66.0 09:20:51 INFO - PROCESS | 1668 | -------- Summary: end -------- 09:20:51 INFO - PROCESS | 1668 | 09:20:52 INFO - TEST-INFO | 1668: exit 0 09:20:52 INFO - TEST-OK | kraken | took 58693ms 09:20:52 INFO - SUITE-END | took 1179s 09:20:52 INFO - Completed test suite (00:19:39) 09:20:52 INFO - javascript benchmark 09:20:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2834.291427143214, 3323.53470514128, 3346.3474622448616, 3357.1607098225445, 3401.6768407001327, 5596.972015139929, 5706.28671416073, 5732.140178973156, 5742.39704830993, 5769.538436925046, 5666.631668941526, 5702.116171993337, 5711.828645140642, 5714.3999880012625, 5716.22830917826, 5178.456262092483, 5222.712750798704, 5230.2416149658275, 5256.316678831747, 5256.474352564751, 5305.946940530604, 5310.7610157543, 5315.760790764405, 5333.973330133344, 5339.866503337409, 5215.843524694265, 5269.683818970855, 5273.551748101416, 5303.522682958533, 5321.494458026501, 5303.443138470444, 5319.148936170213, 5333.893322133536, 5335.039692855285, 5398.163284690879, 5094.337736094284, 5117.744112794346, 5169.922451163236, 5257.342832145981, 5273.841784746463, 5617.354004289518, 5671.6597004179885, 5711.857203569903, 5731.971340143315, 5741.33974592923, 6244.937550624481, 6275.497960163221, 6278.529110316699, 6287.654179020152, 6300.5589608727405, 6190.102435146879, 6264.9686751566405, 6291.716872740738, 6356.713947872357, 6360.363963603649, 4828.830990915301, 5790.594658373873, 5797.971010144923, 5805.187273781674, 5809.738561764731, 5334.759935802898, 6262.2172228471445, 6339.11252424661, 6344.143540622049, 6347.904781428282, 5103.183490641553, 6105.084237364431, 6111.318268824403, 6119.374694480196, 6140.206206176179, 6197.380261973766, 6201.844953876099, 6237.438630523275, 6238.594491358047, 6250.9062364064575, 6224.782132625336, 6228.688565571703, 6232.844178895473, 6252.343503932022, 6262.6868656566985, 5415.241866138744, 6215.81352559424, 6232.501399887998, 6242.500599951993, 6251.281102673251, 4837.129316723023, 4897.461279259279, 4902.0, 4915.729634870117, 4924.655274130777, 4342.174986752507, 4394.2969124939855, 4420.58004489573, 4432.955670443319, 4467.285234362552, 5793.0, 5814.244148260699, 5816.621919575214, 5817.185594016841, 5836.358000619928, 6144.003198960356, 6324.462420694293, 6338.778142764981, 6341.207349081365, 6352.8411789704705, 5687.175359572839, 5689.9146512802345, 5704.201411802351, 5723.227364305765, 5769.68266745333, 5395.514403703685, 5405.5135037846785, 5409.0804563223555, 5411.458854114636, 5440.510354068074, 6293.213348331458, 6304.338044505261, 6308.684565771785, 6319.304876463677, 6319.778807741707, 6111.052786818051, 6191.226096737908, 6205.937940620536, 6256.906146407899, 6295.213098362705, 6236.656983865931, 6251.562390632612, 6287.497000240061, 6289.088082228142, 6313.210848643919, 6212.1303017578475, 6212.192414986023, 6217.440430361289, 6230.813075607739, 6236.781712640036, 6129.14485165794, 6189.1025801257665, 6266.373362663697, 6295.055741638791, 6309.1167236587835, 5832.795852145155, 6116.0, 6125.571210015256, 6172.537059720449, 6188.257409110935, 6025.337212906665, 6135.877282454237, 6153.261608606996, 6156.722947467285, 6165.84585385369, 5709.714514274353, 5962.880742385215, 5991.430814072656, 6001.06983417571, 6003.7898673546215, 5922.881542369042, 5965.254343207099, 5993.370696077023, 5993.940060599338, 6004.279486461653, 5809.709514524172, 5816.651000939957, 5821.214136091573, 5833.854153646193, 5848.4151584842875], "value": 5691.121814237954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.5961034095167, 474.4638558428975, 498.8553319537333, 531.195239212593, 540.297613102966, 30983.690163098363, 40209.19581608366, 41173.79413102934, 41364.172716545654, 41465.58534414655, 43353.566464335345, 43597.564024359745, 43689.0, 43741.7812910935, 43834.0, 9469.431834089943, 9639.036096390375, 9653.227741780676, 9653.75865603362, 9654.5172741363, 9519.238460923145, 9596.184324332406, 9600.0, 9615.903840961571, 9617.615295388176, 9560.904390956106, 9628.277879159055, 9630.229581633488, 9642.517874106301, 9642.566084526214, 9548.952255238713, 9602.759931001747, 9636.373635713657, 9636.71089867305, 9640.807183856354, 9492.857607135898, 9568.186704130158, 9571.425714457086, 9582.185514231298, 9583.472908990003, 40492.19015619671, 40886.59113408887, 40894.38658420126, 40910.0, 40948.79525602383, 3383.712384447325, 3418.453047512386, 3421.4012547804036, 3425.657434256579, 3446.5864096308605, 30764.692353076407, 31036.53445198324, 31273.687263127304, 31363.686363136305, 31467.685323146703, 3105.0, 3217.1635374802486, 3222.951655725166, 3258.152880251128, 3312.668733126692, 459277.0, 463245.0, 463556.0, 463586.9999999966, 471402.0, 56031.43968560344, 58472.41527584755, 62280.0, 62866.68566657229, 62867.37132628615, 34425.139371515914, 35241.47137792935, 59689.10466343008, 61495.69252153711, 66110.0, 16165.515034548982, 16618.0, 16621.584460388585, 16657.33370665184, 16663.0, 10666.839997400046, 12644.873551264369, 12748.043896707784, 12769.61691149267, 12771.361431928366, 19432.500435963342, 19483.14092811924, 19490.74557672904, 19523.13103434552, 19537.80569890066, 6322.936770632326, 7048.682809273595, 7851.332636725942, 7977.800554986172, 8013.919860801434, 857.3484152044491, 938.2165891480554, 975.2831668723483, 983.3952119446632, 1033.4729288063143, 22974.540509189723, 25134.371640709127, 30562.624681889367, 30648.0, 31254.218644533616, 1051.30613794895, 1059.6079450603327, 1072.860528131338, 1075.9408232547285, 1082.1126676125502, 15848.76226856598, 16002.062479238708, 16144.75782863258, 16170.67658646844, 16252.51242462728, 660773.6961315259, 664014.0, 664265.0, 664925.0, 667202.0, 58482.0, 58970.70514647399, 59159.70420147958, 59193.40806591879, 59247.0, 1151.740858306891, 1245.7508498300194, 1278.8849003589721, 1389.9235542045285, 1405.669667628086, 28387.574186386806, 30773.692263077082, 31390.0, 32393.35213295768, 32446.35107297888, 51883.481165189376, 56505.0, 58364.708176458844, 58531.41468585259, 61163.69418153059, 489836.0, 610609.0, 610859.0, 611293.0, 612388.0, 34945.65054349424, 35185.824070879484, 35351.93944181679, 36477.45283820692, 42798.78600606977, 45000.54999449963, 46821.82945426391, 47193.528064718914, 47525.0, 48372.274415884494, 37868.810655946545, 39531.80234098811, 39875.80062099787, 40663.39004914869, 40973.38539921963, 34293.314133717686, 37935.0, 39190.608093918694, 39272.60727392689, 40744.59255407408, 38424.20441139221, 38912.80543597264, 39093.609063908996, 39239.0, 39261.0, 32125.839370803933, 33893.0, 34712.65287347094, 34901.30197395987, 35007.949761507196, 23258.138349442244, 25033.87483062573, 25383.0, 25480.490390192463, 26170.0], "value": 20154.41048216651, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.5559905912434, 376.55754488476043, 379.8214839025659, 395.3338624417858, 404.842111576485, 14170.63001008852, 15439.382424702999, 18538.0, 20797.99999999998, 21040.473988150305, 20091.29680461184, 20330.898345508253, 21037.68443473349, 21379.358619241448, 21466.277418967802, 5325.174597937326, 5522.66863988162, 5527.944720552783, 5531.944680553183, 5538.363088244843, 3903.941440878389, 4119.814608342616, 5459.454054594548, 5821.243238379005, 6038.5471089668235, 6092.72582733778, 6146.539009574278, 6149.6617686027475, 6161.845953851145, 6163.691815409234, 5827.242458480391, 6030.396960303979, 6071.392860713893, 6071.787487437962, 6085.361037091086, 5226.0, 5715.342735585419, 5866.823995280149, 5874.441928016831, 5880.5883588148845, 13483.662908427368, 13643.181409115385, 17814.020228887406, 25011.37471563207, 25332.0, 2303.8272129590346, 2422.5760491913843, 2534.6831646044243, 2690.825096368749, 2957.6155099837097, 9863.753406164831, 10460.215483838743, 10482.842757358645, 10543.683689489328, 10567.996040376152, 1823.1157888424236, 1883.1055248756868, 1922.2311075569744, 1936.3513223070288, 1971.1129991503738, 102468.48765756123, 108894.3665845013, 111453.0, 118467.0, 118514.62970740623, 11284.035024589155, 11301.604443844426, 11484.712882177846, 11490.655280341603, 11635.080211765133, 14529.273536323353, 14531.564053078424, 14589.781153282709, 14592.781108283385, 14600.634984125269, 8349.54077525742, 8508.106648801786, 8510.829783404297, 8519.659213631385, 8662.956685216534, 8327.458715183493, 8363.121872203452, 8502.149785021573, 8542.572871356408, 8547.786305342415, 3691.9630803692157, 4420.160169567836, 4506.594406503365, 4590.380298659705, 4603.424571928509, 1559.7738327942384, 1859.3492277702967, 1921.8846869187894, 1927.759030121235, 1930.2086144680613, 862.9956850215834, 923.2798417234568, 978.3319440354179, 978.554757585297, 983.0759086458706, 20996.950152492314, 21566.676499852514, 21587.568248635253, 21637.459063523536, 21659.350219493124, 874.9343799215085, 904.1862323908535, 904.4844438669895, 922.6908985489936, 962.5668449197889, 8665.870011949826, 10659.360438373722, 11209.831852522218, 11397.487113079931, 11422.428878556038, 460033.0, 465788.0, 468405.0, 541682.0, 542979.0, 128931.35534322268, 129299.0, 130791.0, 135623.0, 138176.0, 935.4948327902993, 974.7319487141093, 991.177322822056, 991.620111731835, 993.4072071892355, 19216.423507294803, 19437.820868513336, 19700.80299197047, 19789.505262368555, 19856.90071549633, 43155.0, 47082.76458617685, 51129.74435127801, 56275.0, 57556.0, 62987.0, 80778.59610702144, 80820.81384936775, 82379.0, 82896.58551707445, 6977.162740471176, 7920.445568810128, 7959.920400795918, 8059.0, 8234.752957411287, 7274.8908766367485, 9870.457124858201, 10770.246082774443, 12848.310366716269, 13258.668533286746, 8951.641934322553, 10873.728156796144, 11152.832707509231, 11295.661130166109, 11605.6518304451, 11082.279651822606, 11332.093432525544, 11410.828837567276, 11507.079433645122, 11771.352575608424, 10364.948175259075, 10858.34849909008, 11376.0, 11405.885941140483, 11633.302001879914, 7327.670254838438, 7436.516626419266, 7455.254474552501, 7658.617069146632, 7699.846003080019, 9460.621575137193, 10913.126949844152, 11062.668119956414, 11072.778544428906, 11211.383373914514], "value": 9333.34363079073, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.0465674028183, 1532.1843615315847, 1566.0055864526025, 1773.538879891228, 1915.750952376191, 7790.6104694765245, 8256.380771442136, 8466.872996905044, 8791.252743516792, 8825.293976481882, 8626.396152269344, 8731.083236260181, 8744.737657870273, 8746.431481953668, 8785.824283514326, 1980.87124336918, 2208.392692009694, 2382.332946774906, 2382.6902502674716, 2384.403899025244, 1951.4048215294329, 2370.608849539824, 2386.773256540626, 2390.916317928873, 2392.6411038344213, 2098.8845613491253, 2282.9840720879215, 2348.377679914824, 2368.763123687626, 2379.357573455166, 2325.5348930213854, 2336.836421450499, 2346.2609278077352, 2347.4014126397747, 2349.1308215960203, 2332.090484710964, 2337.777911098443, 2338.018032426385, 2339.4619237575284, 2342.5314937012668, 8518.105598912087, 8760.956195218983, 8793.296536277083, 8884.777880553038, 8889.511076890769, 5207.114790485626, 5366.517013468768, 5369.865753356158, 5373.516383525502, 5389.003034438641, 3585.713142948558, 3601.603823579404, 3665.725070619714, 3673.081729567608, 3674.761140525857, 1049.6536143072844, 1058.0847566854693, 1064.3879769132702, 1072.8068947589431, 1084.772197838447, 10579.68260952165, 10629.574817007311, 11302.265352752127, 11367.431628418628, 11383.0, 4351.0, 4403.581659742319, 4586.472555656075, 4594.632429405639, 4596.931046034312, 9035.051319611346, 9037.412568183046, 9040.186383225542, 9045.638174473079, 9050.366474346733, 5233.659812112277, 5235.0, 5244.449332819999, 5247.055530004636, 5257.08001099806, 4090.7750073745633, 4372.0818628088455, 4546.04533048057, 4890.168671325883, 4905.386826646669, 3314.9557889264806, 3493.336266109431, 3693.316736403774, 3782.735208535431, 3798.6771124454176, 1143.8398624192619, 1164.6622479481025, 1165.0621249893748, 1176.3236139219982, 1177.8115501519887, 798.5448294472095, 889.6797153024905, 908.3096846396786, 917.454114801692, 956.4452617481844, 5256.237845512379, 5694.857628559236, 5979.222701048923, 6033.4871535919065, 6126.356732543019, 392.72509243529646, 430.84489583749826, 445.69469913108975, 447.96192323652855, 452.7804015052697, 3328.766986310935, 3956.1098752780395, 3965.7422267552515, 4085.8774236772942, 4087.693422993287, 10279.280450368402, 10565.524551395205, 10681.252312338062, 10683.0, 10688.465576721133, 10647.0, 10662.41356725372, 10668.946655266674, 10672.946635266773, 10683.145348372267, 7841.372690184771, 8101.392395570356, 8226.753197404087, 8239.711610093618, 8242.258196762321, 5734.827955161351, 5837.211976383303, 5842.883142337045, 5859.326177489644, 5914.142449344907, 9871.654492092743, 9901.455419951684, 9932.553035113582, 10008.199344052313, 10038.899611004088, 9140.542972851197, 9165.220956218796, 10024.799504009734, 10037.34757240777, 10115.089641931967, 6946.270641582561, 7160.785176444507, 7269.273072692688, 7379.594122323324, 7487.625618718933, 8140.267375936194, 8148.470349427268, 8152.796180095545, 8230.547319897463, 8237.723152911309, 7357.668904899399, 7382.040334756447, 7434.665440055102, 7445.10658720957, 7494.325510704063, 7156.856862862818, 7350.338469537767, 7402.074740657418, 7414.073240844895, 7448.0, 6892.310768923067, 6927.480438966997, 6982.581045137308, 7061.858762824818, 7064.964675176591, 4863.392075990501, 4934.136526107845, 4949.455559888482, 5005.724685142352, 5053.36832895888, 4985.227289770091, 5021.07110184617, 5059.696418214919, 5111.028904508132, 5176.2494438306985], "value": 4416.972378959629, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.31387808041507, 300.6993006993007, 307.3100888505305, 314.3242029636282, 316.2331346484774, 4671.626269898408, 4886.827161481246, 5129.281900533923, 5181.5854731621475, 5201.999999999995, 4875.780589873455, 4954.950450495494, 5087.694738315704, 5114.565261952729, 5211.661242019266, 3450.516927630134, 3927.7643341399466, 3990.64084232419, 4005.6995725320717, 4018.8995275118136, 3471.63547827477, 3952.430919902058, 3954.536821376095, 3965.03730653327, 3983.9601603984024, 3821.312163810513, 3974.721769476138, 3979.4030895365636, 3984.3226651469176, 3995.900102497432, 3433.725301975836, 3958.9010274743073, 3984.143409167043, 3992.141689536749, 4042.191561687645, 3504.737144714157, 3509.8242088900247, 3511.964880351215, 3521.8767343142868, 3528.700060494861, 4890.291479263488, 5060.418051924039, 5074.67014644047, 5082.720450375228, 5091.065395149856, 3942.0, 4438.7114837535455, 4518.615917647004, 4518.751468669222, 4534.322300748744, 4198.580141985807, 4954.777035033396, 5237.004969055867, 5261.894762104736, 5417.377001644822, 3583.94624080639, 3781.7920014399197, 3806.2577797329254, 3807.58116607173, 3827.311084004878, 4650.697704649187, 5015.423226328946, 5449.964506743706, 5710.71446427677, 5710.972025035492, 3208.8235147066666, 3362.9663703363144, 3859.7298189127055, 3869.767813931117, 3906.4921560196995, 3104.764569509949, 3292.234523000691, 3666.9365883893374, 3927.7054220933546, 4000.439938408625, 4189.560096189917, 4464.620507256855, 4531.932021019687, 4552.7495987720995, 4556.794944227518, 5487.338640759229, 5487.835364939058, 5538.867535998669, 5549.223108764776, 5612.382637909826, 1064.9989010330419, 1302.4790083966489, 1324.675454513631, 1325.9801102983463, 1351.1622793867668, 752.712861007672, 828.188375392119, 857.3355649371662, 857.9528125953008, 870.4385671242013, 3880.553736320303, 4218.873433796991, 4913.140200464904, 5012.69669885825, 5091.134507133796, 4482.955170448319, 5031.320771695774, 5552.361478429952, 5616.315105468425, 5628.352739435018, 4200.915981680349, 4397.559354558187, 4762.380890484214, 4810.278458231293, 4860.902781944341, 2318.0, 2861.7996740227986, 2892.3203047283873, 2902.912912612625, 2915.454808951259, 4670.953290467051, 4954.8761280968265, 5160.561352285098, 5708.372079071299, 5763.827085187451, 4690.390249267573, 5161.354830646169, 5613.915791263134, 5635.5773317001385, 5684.744186511617, 4904.534069263485, 5407.891842163213, 5606.299212598426, 5649.491545760901, 5704.401037891044, 5069.108449009358, 5467.289252397322, 5511.063119269654, 5514.105094825249, 5519.806806761744, 4510.548945105464, 4715.316279139437, 5392.245085687929, 5414.29614150158, 5418.126039881504, 4932.926006109983, 5139.537441630271, 5191.402988656353, 5649.943500564942, 5767.653940763567, 3743.700503959731, 3748.8687895923513, 3749.5500539935383, 3765.604611515861, 3766.227923275777, 4314.309710446313, 4529.524399938091, 5257.658252213594, 5431.972840135774, 5442.183672449164, 5314.229436731575, 5337.2513155724055, 5341.919871201857, 5356.0, 5411.918821217606, 4712.340272361804, 5213.552501782477, 5373.167159090347, 5374.4625537445945, 5394.973025134849, 4375.562443755599, 4728.929066063943, 4742.241241401359, 4744.596709279612, 4842.661013729005, 4209.452771139732, 4330.26385514457, 4679.040796636749, 5318.308619879391, 5358.866028349323, 4566.566176213254, 4671.929921051119, 5059.382541482857, 5295.311609490742, 5332.093544097435], "value": 3904.129585643546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.5277282288808, 791.1574173505217, 796.0526972902246, 818.7339114787695, 828.9336853051757, 15259.160746158957, 15344.693106137871, 15891.44379946702, 16231.675366492664, 16418.50744477666, 14306.856931430682, 14713.190774507399, 14716.852831471682, 14717.632059198526, 14721.043132196426, 699.3985172751429, 869.5000374784493, 893.8614514750192, 894.2890402130322, 894.838928992781, 718.392957950531, 875.0549406640845, 875.3522393428881, 879.1164879296298, 882.2633101360371, 954.3510412919212, 955.1833182628857, 955.2405837359316, 955.5652178258875, 957.1003256938491, 949.5774380400725, 949.9050094990515, 951.0774548687762, 951.4148798488949, 951.8524628682566, 537.9596530260206, 556.4324389123071, 676.1953275602018, 695.7947405515334, 701.5790525684548, 15442.227888605525, 15508.767368489369, 15511.5346539604, 15530.301136448887, 15606.765898511418, 13726.45094196231, 14068.859311406857, 14176.078554893898, 14177.0, 14395.78406323906, 11325.037371823404, 12376.876231237753, 12707.682307942283, 12733.681657958625, 12748.490060397573, 4416.094700586372, 4538.591526762574, 4546.227141385973, 4555.293929440942, 4579.3131030345385, 90861.09138908659, 112829.30756038747, 118272.40863795627, 118275.4086229572, 118687.62624747543, 10885.292455990335, 11133.276337038067, 11151.386673733023, 11437.828432573519, 11515.366654834064, 22350.329490115324, 22407.77592224057, 22497.212597559006, 22512.887435563043, 22559.097636094702, 7759.57322347265, 7947.841043179104, 7987.9201207988335, 8031.277185053374, 8033.3974951878845, 4217.34631132175, 4344.044310251739, 4371.732197662643, 4414.889627759268, 4501.347304640809, 3469.965300347015, 3496.423090190141, 3594.1913069559664, 3600.279944011208, 3600.405933021022, 650.7852408705116, 654.5011922697065, 656.4354654996699, 670.1589505170979, 673.6429692263108, 528.7224207291202, 555.4528789142691, 579.2874764040254, 610.7282259394616, 631.2298995225817, 8441.70454034106, 8826.823463530693, 8852.291816654651, 8940.240079593308, 9103.499307538017, 586.973586188614, 603.7253049862302, 636.4367534731796, 682.4885961726887, 697.9616121113286, 4373.497047839475, 4403.911921761547, 4621.792019359137, 4922.040202160544, 5021.5250926775025, 95924.04075959291, 120830.0, 137495.0, 143922.0, 164322.0, 82986.0, 106905.0, 112086.31870521948, 121285.39357303157, 135271.0, 73983.15042123882, 100314.49528257082, 106210.0, 110487.0, 113354.43322783333, 9780.804383912424, 9935.254855885692, 9954.054364835329, 10023.248256380946, 10068.748281293027, 40196.79409617716, 56844.71577642085, 57321.713391434445, 57698.0, 60487.69756151191, 70253.94619080615, 74682.0, 87723.0, 90882.54558727164, 92492.53753731189, 13059.766293696035, 13510.054296199172, 13535.932320338336, 13921.79117313221, 13958.0, 15005.774913376526, 15155.545333640008, 15385.384584616937, 15621.062736235863, 15731.528054158394, 14074.0, 14382.496612618508, 14414.834510675268, 14427.711445771236, 14758.704825903636, 11934.880651193376, 13837.446502140205, 13890.791638125638, 14681.192534410282, 14707.92646036763, 13070.477180913038, 13098.476060957455, 13460.865391345962, 13732.480392499136, 13872.098313609988, 11336.829947550628, 11585.7103572408, 11707.590234341757, 11741.823872641746, 11861.0, 10911.508982095665, 11194.525710318772, 11515.53937842477, 11541.422928853355, 11556.88443115558], "value": 7849.769915392305, "unit": "score"}], "name": "dromaeo_css", "value": 7247.5874967446825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 114.0, 112.0, 112.0, 111.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 114.0, 148.0, 115.0, 113.0, 112.0, 114.0, 145.0, 111.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 183.0, 185.0, 185.0, 183.0, 182.0, 181.0, 181.0, 182.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 87.0, 64.0, 62.0, 59.0, 58.0, 83.0, 57.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 91.0, 69.0, 68.0, 70.0, 92.0, 67.0, 68.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 87.0, 84.0, 88.0, 85.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 91.0, 93.0, 93.0, 93.0, 93.0, 93.0, 111.0, 149.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 81.0, 82.0, 82.0, 85.0, 86.0, 82.0, 84.0], "value": 83.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 52.0, 53.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 70.0, 74.0, 70.0, 69.0, 68.0, 68.0, 70.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 86.0, 83.0, 85.0, 88.0, 88.0, 103.0, 109.0, 75.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 145.0, 143.0, 143.0, 144.0, 147.0, 146.0, 143.0, 207.0, 152.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 170.0, 168.0, 169.0, 174.0, 170.0, 168.0, 170.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 70.0, 69.0, 69.0, 68.0, 67.0, 68.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1434.1, "alertThreshold": 2.0}]} 09:20:52 INFO - Return code: 0 09:20:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:52 INFO - # TBPL SUCCESS # 09:20:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:52 INFO - 2016-10-14 09:20:52.750 screenresolution[1672:9881] starting screenresolution argv=screenresolution get 09:20:52 INFO - 2016-10-14 09:20:52.753 screenresolution[1672:9881] Display 0: 1600x1200x32@60 09:20:52 INFO - 2016-10-14 09:20:52.759 screenresolution[1673:9884] starting screenresolution argv=screenresolution list 09:20:52 INFO - Available Modes on Display 0 09:20:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:52 INFO - Intel Iris: 09:20:52 INFO - Chipset Model: Intel Iris 09:20:52 INFO - Type: GPU 09:20:52 INFO - Bus: Built-In 09:20:52 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:52 INFO - Vendor: Intel (0x8086) 09:20:52 INFO - Device ID: 0x0a2e 09:20:52 INFO - Revision ID: 0x0009 09:20:52 INFO - Displays: 09:20:52 INFO - Display: 09:20:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:52 INFO - Display Serial Number: 1600x1200 60 09:20:52 INFO - Main Display: Yes 09:20:52 INFO - Mirror: Off 09:20:52 INFO - Online: Yes 09:20:52 INFO - Rotation: Supported 09:20:53 INFO - Return code: 0 09:20:53 INFO - Running post-action listener: _resource_record_post_action 09:20:53 INFO - [mozharness: 2016-10-14 16:20:53.001217Z] Finished run-tests step (success) 09:20:53 INFO - Running post-run listener: _resource_record_post_run 09:20:53 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37360640; Write bytes: 710418944; Read time: 246; Write time: 1971 09:20:53 INFO - TinderboxPrint: CPU usage
53.6% 09:20:53 INFO - TinderboxPrint: I/O read bytes / time
37,360,640 / 246 09:20:53 INFO - TinderboxPrint: I/O write bytes / time
710,418,944 / 1,971 09:20:53 INFO - TinderboxPrint: CPU idle
2,254.6 (46.4%) 09:20:53 INFO - TinderboxPrint: CPU system
972.6 (20.0%) 09:20:53 INFO - TinderboxPrint: CPU user
1,630.4 (33.6%) 09:20:53 INFO - TinderboxPrint: Swap in / out
445,317,120 / 0 09:20:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401138176; Write bytes: 395786240; Read time: 29594; Write time: 805 09:20:53 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35279872; Write bytes: 298273280; Read time: 226; Write time: 1125 09:20:53 INFO - Running post-run listener: _upload_blobber_files 09:20:53 INFO - Blob upload gear active. 09:20:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:53 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 09:20:53 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:53 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:53 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:54 INFO - (blobuploader) - INFO - Done attempting. 09:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:55 INFO - (blobuploader) - INFO - Done attempting. 09:20:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:57 INFO - (blobuploader) - INFO - Done attempting. 09:20:57 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:57 INFO - Return code: 0 09:20:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57006a11c34f16da343a369755c863a2bfe4ec995c59a15492131f9f26bde78e4bc7e1f4f6b94b26c8cceed0c3574e24e7e1f4c59010b9ca2bbe282abfa23713", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90d5807bd42b52d3b42633ee29552b28ffb2d70f348676bd2829fcb908c35cf9afbc18d670314cedd7fd301945038dface613d0ab0454a2c956687710cc2205a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d905827a1d60b16a5cc91ad92dcc013e1ae040f8ca85f411e3835f5d5ebefa73673118696e58786fc586a12ac8a085379b6c562105ec0d70c9c7d69fedb08604"} 09:20:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:57 INFO - Contents: 09:20:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57006a11c34f16da343a369755c863a2bfe4ec995c59a15492131f9f26bde78e4bc7e1f4f6b94b26c8cceed0c3574e24e7e1f4c59010b9ca2bbe282abfa23713", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90d5807bd42b52d3b42633ee29552b28ffb2d70f348676bd2829fcb908c35cf9afbc18d670314cedd7fd301945038dface613d0ab0454a2c956687710cc2205a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d905827a1d60b16a5cc91ad92dcc013e1ae040f8ca85f411e3835f5d5ebefa73673118696e58786fc586a12ac8a085379b6c562105ec0d70c9c7d69fedb08604"} 09:20:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:57 INFO - Copying logs to upload dir... 09:20:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.580369 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-14 09:20:57.755381) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:57.762909) ========= 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.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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/Fx-Team/sha512/57006a11c34f16da343a369755c863a2bfe4ec995c59a15492131f9f26bde78e4bc7e1f4f6b94b26c8cceed0c3574e24e7e1f4c59010b9ca2bbe282abfa23713", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90d5807bd42b52d3b42633ee29552b28ffb2d70f348676bd2829fcb908c35cf9afbc18d670314cedd7fd301945038dface613d0ab0454a2c956687710cc2205a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d905827a1d60b16a5cc91ad92dcc013e1ae040f8ca85f411e3835f5d5ebefa73673118696e58786fc586a12ac8a085379b6c562105ec0d70c9c7d69fedb08604"} build_url:https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010941 build_url: 'https://queue.taskcluster.net/v1/task/abFjVlQyQE-dB4QPh0PlBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57006a11c34f16da343a369755c863a2bfe4ec995c59a15492131f9f26bde78e4bc7e1f4f6b94b26c8cceed0c3574e24e7e1f4c59010b9ca2bbe282abfa23713", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90d5807bd42b52d3b42633ee29552b28ffb2d70f348676bd2829fcb908c35cf9afbc18d670314cedd7fd301945038dface613d0ab0454a2c956687710cc2205a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d905827a1d60b16a5cc91ad92dcc013e1ae040f8ca85f411e3835f5d5ebefa73673118696e58786fc586a12ac8a085379b6c562105ec0d70c9c7d69fedb08604"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:57.799865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:57.800214) ========= 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.nvGnE4W6we/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.SRUynctRvJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005038 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:20:57.849426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:20:57.852816) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:20:57.853166) ========= ========= Total master_lag: 0.19 =========