builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1477485443.81 results: success (0) buildid: 20161025185822 builduid: 597bb44274da41c9a5d57fe3793b73d1 revision: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.811400) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.811735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.812003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.920135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.920398) ========= 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.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-26 05:37:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2016-10-26 05:37:24 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.513368 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.458404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.458680) ========= 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.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.028001 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.506882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 05:37:24.507398) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 --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 b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-26 05:37:24,583 truncating revision to first 12 chars 2016-10-26 05:37:24,584 Setting DEBUG logging. 2016-10-26 05:37:24,584 attempt 1/10 2016-10-26 05:37:24,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b1b18f25c0ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 05:37:25,636 unpacking tar archive at: fx-team-b1b18f25c0ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.308343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 05:37:25.837637) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.838007) ========= script_repo_revision: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.838435) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.838714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.857289) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-26 05:37:25.857672) ========= /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.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:37:26 INFO - MultiFileLogger online at 20161026 05:37:26 in /builds/slave/test 05:37:26 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 05:37:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:26 INFO - {'append_to_log': False, 05:37:26 INFO - 'base_work_dir': '/builds/slave/test', 05:37:26 INFO - 'blob_upload_branch': 'Fx-Team', 05:37:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:26 INFO - 'branch': 'Fx-Team', 05:37:26 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:26 INFO - 'config_files': ('talos/mac_config.py',), 05:37:26 INFO - 'default_actions': ('clobber', 05:37:26 INFO - 'read-buildbot-config', 05:37:26 INFO - 'download-and-extract', 05:37:26 INFO - 'populate-webroot', 05:37:26 INFO - 'create-virtualenv', 05:37:26 INFO - 'install', 05:37:26 INFO - 'run-tests'), 05:37:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:26 INFO - 'download_minidump_stackwalk': True, 05:37:26 INFO - 'download_symbols': 'ondemand', 05:37:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:26 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:26 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:26 INFO - 'installer_path': 'installer.exe', 05:37:26 INFO - 'log_level': 'info', 05:37:26 INFO - 'log_name': 'talos', 05:37:26 INFO - 'log_to_console': True, 05:37:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:37:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:37:26 INFO - 'opt_config_files': (), 05:37:26 INFO - 'pip_index': False, 05:37:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:26 INFO - 'cmd': ('bash', 05:37:26 INFO - '-c', 05:37:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:26 INFO - 'enabled': True, 05:37:26 INFO - 'halt_on_failure': False, 05:37:26 INFO - 'name': 'check_screen_resolution'},), 05:37:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:26 INFO - 'cmd': ('bash', 05:37:26 INFO - '-c', 05:37:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:26 INFO - 'enabled': True, 05:37:26 INFO - 'halt_on_failure': False, 05:37:26 INFO - 'name': 'check_screen_resolution'},), 05:37:26 INFO - 'run_cmd_checks_enabled': True, 05:37:26 INFO - 'sps_profile': False, 05:37:26 INFO - 'sps_profile_interval': 0, 05:37:26 INFO - 'suite': 'dromaeojs', 05:37:26 INFO - 'system_bits': '32', 05:37:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:26 INFO - 'title': 't-yosemite-r7-0093', 05:37:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:26 INFO - 'use_talos_json': True, 05:37:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:26 INFO - 'work_dir': 'build'} 05:37:26 INFO - [mozharness: 2016-10-26 12:37:26.065574Z] Running clobber step. 05:37:26 INFO - Running pre-action listener: _resource_record_pre_action 05:37:26 INFO - Running main action method: clobber 05:37:26 INFO - rmtree: /builds/slave/test/build 05:37:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:28 INFO - Running post-action listener: _resource_record_post_action 05:37:28 INFO - [mozharness: 2016-10-26 12:37:28.410666Z] Finished clobber step (success) 05:37:28 INFO - [mozharness: 2016-10-26 12:37:28.410810Z] Running read-buildbot-config step. 05:37:28 INFO - Running pre-action listener: _resource_record_pre_action 05:37:28 INFO - Running main action method: read_buildbot_config 05:37:28 INFO - Using buildbot properties: 05:37:28 INFO - { 05:37:28 INFO - "project": "", 05:37:28 INFO - "product": "firefox", 05:37:28 INFO - "script_repo_revision": "production", 05:37:28 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:37:28 INFO - "repository": "", 05:37:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:37:28 INFO - "buildid": "20161025185822", 05:37:28 INFO - "pgo_build": "False", 05:37:28 INFO - "basedir": "/builds/slave/test", 05:37:28 INFO - "buildnumber": 27, 05:37:28 INFO - "slavename": "t-yosemite-r7-0093", 05:37:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:37:28 INFO - "platform": "yosemite_r7", 05:37:28 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:37:28 INFO - "branch": "fx-team", 05:37:28 INFO - "repo_path": "integration/fx-team", 05:37:28 INFO - "revision": "b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1", 05:37:28 INFO - "stage_platform": "macosx64", 05:37:28 INFO - "builduid": "597bb44274da41c9a5d57fe3793b73d1", 05:37:28 INFO - "slavebuilddir": "test" 05:37:28 INFO - } 05:37:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:37:28 INFO - Running post-action listener: _resource_record_post_action 05:37:28 INFO - [mozharness: 2016-10-26 12:37:28.414128Z] Finished read-buildbot-config step (success) 05:37:28 INFO - [mozharness: 2016-10-26 12:37:28.414240Z] Running download-and-extract step. 05:37:28 INFO - Running pre-action listener: _resource_record_pre_action 05:37:28 INFO - Running main action method: download_and_extract 05:37:28 INFO - mkdir: /builds/slave/test/build/tests 05:37:28 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')]} 05:37:28 INFO - trying https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/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 05:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/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 05:37:29 INFO - Downloaded 1199 bytes. 05:37:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:29 INFO - Using the following test package requirements: 05:37:29 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:29 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:37:29 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:37:29 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'jsshell-mac.zip'], 05:37:29 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:37:29 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:29 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:37:29 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:37:29 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:37:29 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:29 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:37:29 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:37:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:30 INFO - Http code: 200 05:37:30 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 05:37:30 INFO - x-amz-cf-id: IA23HIkNBm7qqwf1Nx8viCaP6ii9uHIO93SwrgpPeq2blvz1xix21A== 05:37:30 INFO - x-amz-version-id: m0xOcBGqqv0MOgpPsX9fI9CpxiTvSkOZ 05:37:30 INFO - Expected file size: 16423362 05:37:30 INFO - Obtained file size: 16423362 05:37:36 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 05:37:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:37 INFO - Http code: 200 05:37:37 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 05:37:37 INFO - x-amz-cf-id: tl1i4-vJJIS4hqk2zB2Eqz_ION1oGIYlE7oFkifuMz0dgmsdZZ1owA== 05:37:37 INFO - x-amz-version-id: m0xOcBGqqv0MOgpPsX9fI9CpxiTvSkOZ 05:37:37 INFO - Expected file size: 16423362 05:37:37 INFO - Obtained file size: 16423362 05:37:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:37:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:37:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:37:44 INFO - Http code: 200 05:37:44 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 05:37:44 INFO - x-amz-cf-id: nNzqHhBTXOpJh4-7w0Q3HX8XkfnHSslVINkuOc7qkVOzG6kkWQocBw== 05:37:44 INFO - x-amz-version-id: BBSq4.5gYhV_V2QsJwf74wEF2YXjLqJo 05:37:45 INFO - Expected file size: 14409028 05:37:45 INFO - Obtained file size: 14409028 05:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:45 INFO - trying https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/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 05:37:48 INFO - Downloaded 111194726 bytes. 05:37:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:48 INFO - mkdir: /builds/slave/test/properties 05:37:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:48 INFO - Contents: 05:37:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - Running post-action listener: set_extra_try_arguments 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.767789Z] Finished download-and-extract step (success) 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.767969Z] Running populate-webroot step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: populate_webroot 05:37:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:48 INFO - '--tppagecycles', 05:37:48 INFO - '7'], 05:37:48 INFO - 'tests': ['tsvgm']}}, 05:37:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s', 05:37:48 INFO - '--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}, 05:37:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}}, 05:37:48 INFO - 'talos.zip': {'path': '', 05:37:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.786543Z] Finished populate-webroot step (success) 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.786680Z] Running create-virtualenv step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: create_virtualenv 05:37:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:48 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 05:37:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:48 INFO - Using real prefix '/tools/python27' 05:37:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:52 INFO - Installing pip.................done. 05:37:52 INFO - Return code: 0 05:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:37:52 INFO - Reading from file tmpfile_stdout 05:37:52 INFO - Output received: 05:37:52 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) 05:37:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:52 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')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:37:52 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 05:37:52 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 05:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:52 INFO - 'HOME': '/Users/cltbld', 05:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PAGER': '/bin/cat', 05:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:37:52 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - 'XPC_FLAGS': '0x0', 05:37:52 INFO - 'XPC_SERVICE_NAME': '0', 05:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:53 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 05:37:53 INFO - Cleaning up... 05:37:53 INFO - Return code: 0 05:37:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:53 INFO - 'HOME': '/Users/cltbld', 05:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:53 INFO - 'LOGNAME': 'cltbld', 05:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:53 INFO - 'MOZ_NO_REMOTE': '1', 05:37:53 INFO - 'NO_EM_RESTART': '1', 05:37:53 INFO - 'PAGER': '/bin/cat', 05:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:53 INFO - 'PWD': '/builds/slave/test', 05:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:53 INFO - 'SHELL': '/bin/bash', 05:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:37:53 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:53 INFO - 'USER': 'cltbld', 05:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:53 INFO - 'XPC_FLAGS': '0x0', 05:37:53 INFO - 'XPC_SERVICE_NAME': '0', 05:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:53 INFO - Downloading/unpacking psutil>=3.1.1 05:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:57 INFO - Installing collected packages: psutil 05:37:57 INFO - Running setup.py install for psutil 05:37:57 INFO - building 'psutil._psutil_osx' extension 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 INFO - building 'psutil._psutil_posix' extension 05:37:57 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 05:37:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:37:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:37:57 INFO - ^ 05:37:58 INFO - 1 warning generated. 05:37:58 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 05:37:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:58 INFO - Successfully installed psutil 05:37:58 INFO - Cleaning up... 05:37:58 INFO - Return code: 0 05:37:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:58 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')]} 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:58 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')]} 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:58 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:37:58 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 05:37:58 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 05:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:58 INFO - 'HOME': '/Users/cltbld', 05:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:58 INFO - 'LOGNAME': 'cltbld', 05:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:58 INFO - 'MOZ_NO_REMOTE': '1', 05:37:58 INFO - 'NO_EM_RESTART': '1', 05:37:58 INFO - 'PAGER': '/bin/cat', 05:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:58 INFO - 'PWD': '/builds/slave/test', 05:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:58 INFO - 'SHELL': '/bin/bash', 05:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:37:58 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:58 INFO - 'USER': 'cltbld', 05:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:58 INFO - 'XPC_FLAGS': '0x0', 05:37:58 INFO - 'XPC_SERVICE_NAME': '0', 05:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:58 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 05:37:58 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 05:37:58 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 05:37:58 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 05:37:58 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 05:37:58 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 05:38:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:38:01 INFO - Installing collected packages: mozsystemmonitor 05:38:01 INFO - Running setup.py install for mozsystemmonitor 05:38:01 INFO - Successfully installed mozsystemmonitor 05:38:01 INFO - Cleaning up... 05:38:01 INFO - Return code: 0 05:38:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:01 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 05:38:01 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 05:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:01 INFO - 'HOME': '/Users/cltbld', 05:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:01 INFO - 'LOGNAME': 'cltbld', 05:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:01 INFO - 'MOZ_NO_REMOTE': '1', 05:38:01 INFO - 'NO_EM_RESTART': '1', 05:38:01 INFO - 'PAGER': '/bin/cat', 05:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:01 INFO - 'PWD': '/builds/slave/test', 05:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:01 INFO - 'SHELL': '/bin/bash', 05:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:01 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:01 INFO - 'USER': 'cltbld', 05:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:01 INFO - 'XPC_FLAGS': '0x0', 05:38:01 INFO - 'XPC_SERVICE_NAME': '0', 05:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:38:01 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 05:38:01 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 05:38:01 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 05:38:01 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 05:38:01 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 05:38:01 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 05:38:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:38:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 INFO - Downloading functools32-3.2.3-2.tar.gz 05:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:38:04 INFO - warning: no files found matching '*.txt' 05:38:04 INFO - no previously-included directories found matching 'build' 05:38:04 INFO - no previously-included directories found matching 'dist' 05:38:04 INFO - no previously-included directories found matching '.git*' 05:38:04 INFO - Installing collected packages: jsonschema, functools32 05:38:04 INFO - Running setup.py install for functools32 05:38:04 INFO - warning: no files found matching '*.txt' 05:38:04 INFO - no previously-included directories found matching 'build' 05:38:04 INFO - no previously-included directories found matching 'dist' 05:38:04 INFO - no previously-included directories found matching '.git*' 05:38:04 INFO - Successfully installed jsonschema functools32 05:38:04 INFO - Cleaning up... 05:38:04 INFO - Return code: 0 05:38:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:38:04 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')]} 05:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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')]} 05:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:04 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 05:38:04 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 05:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:04 INFO - 'HOME': '/Users/cltbld', 05:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:04 INFO - 'LOGNAME': 'cltbld', 05:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:04 INFO - 'MOZ_NO_REMOTE': '1', 05:38:04 INFO - 'NO_EM_RESTART': '1', 05:38:04 INFO - 'PAGER': '/bin/cat', 05:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:04 INFO - 'PWD': '/builds/slave/test', 05:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:04 INFO - 'SHELL': '/bin/bash', 05:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:04 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:04 INFO - 'USER': 'cltbld', 05:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:04 INFO - 'XPC_FLAGS': '0x0', 05:38:04 INFO - 'XPC_SERVICE_NAME': '0', 05:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:38:05 INFO - Cleaning up... 05:38:05 INFO - Return code: 0 05:38:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:05 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 05:38:05 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 05:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:05 INFO - 'HOME': '/Users/cltbld', 05:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:05 INFO - 'LOGNAME': 'cltbld', 05:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:05 INFO - 'MOZ_NO_REMOTE': '1', 05:38:05 INFO - 'NO_EM_RESTART': '1', 05:38:05 INFO - 'PAGER': '/bin/cat', 05:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:05 INFO - 'PWD': '/builds/slave/test', 05:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:05 INFO - 'SHELL': '/bin/bash', 05:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:05 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:05 INFO - 'USER': 'cltbld', 05:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:05 INFO - 'XPC_FLAGS': '0x0', 05:38:05 INFO - 'XPC_SERVICE_NAME': '0', 05:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:05 INFO - Downloading/unpacking blobuploader==1.2.4 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:38:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:09 INFO - Downloading docopt-0.6.1.tar.gz 05:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:09 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:09 INFO - Running setup.py install for blobuploader 05:38:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:09 INFO - Running setup.py install for requests 05:38:09 INFO - Running setup.py install for docopt 05:38:09 INFO - Successfully installed blobuploader requests docopt 05:38:09 INFO - Cleaning up... 05:38:10 INFO - Return code: 0 05:38:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:10 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')]} 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 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')]} 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:10 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 05:38:10 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 05:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:10 INFO - 'HOME': '/Users/cltbld', 05:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:10 INFO - 'LOGNAME': 'cltbld', 05:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:10 INFO - 'MOZ_NO_REMOTE': '1', 05:38:10 INFO - 'NO_EM_RESTART': '1', 05:38:10 INFO - 'PAGER': '/bin/cat', 05:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:10 INFO - 'PWD': '/builds/slave/test', 05:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:10 INFO - 'SHELL': '/bin/bash', 05:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:10 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:10 INFO - 'USER': 'cltbld', 05:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:10 INFO - 'XPC_FLAGS': '0x0', 05:38:10 INFO - 'XPC_SERVICE_NAME': '0', 05:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-SJAMqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ENUDuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-C1iMlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HWrNcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ujWB9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lgr2Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-hzbyT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-4CYzLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-_sEVX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FqMr2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Edd2Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-iu8vNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-kDwNv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BkLtc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9PYlIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-x1OCmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-_DGk2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:12 INFO - Running setup.py install for manifestparser 05:38:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for mozcrash 05:38:12 INFO - Running setup.py install for mozdebug 05:38:12 INFO - Running setup.py install for mozdevice 05:38:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for mozfile 05:38:12 INFO - Running setup.py install for mozhttpd 05:38:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozinfo 05:38:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozInstall 05:38:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozleak 05:38:13 INFO - Running setup.py install for mozlog 05:38:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for moznetwork 05:38:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozprocess 05:38:13 INFO - Running setup.py install for mozprofile 05:38:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:14 INFO - Running setup.py install for mozrunner 05:38:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:14 INFO - Running setup.py install for mozscreenshot 05:38:14 INFO - Running setup.py install for moztest 05:38:14 INFO - Running setup.py install for mozversion 05:38:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:14 INFO - Cleaning up... 05:38:14 INFO - Return code: 0 05:38:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:14 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:14 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 05:38:14 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 05:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:14 INFO - 'HOME': '/Users/cltbld', 05:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:14 INFO - 'LOGNAME': 'cltbld', 05:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:14 INFO - 'MOZ_NO_REMOTE': '1', 05:38:14 INFO - 'NO_EM_RESTART': '1', 05:38:14 INFO - 'PAGER': '/bin/cat', 05:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:14 INFO - 'PWD': '/builds/slave/test', 05:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:14 INFO - 'SHELL': '/bin/bash', 05:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:14 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:14 INFO - 'USER': 'cltbld', 05:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:14 INFO - 'XPC_FLAGS': '0x0', 05:38:14 INFO - 'XPC_SERVICE_NAME': '0', 05:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-P4OPH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-cc6eNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-67I83G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BKHs7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-5OCK3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-H2H6lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qYCE69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-sZU0mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-VxcRxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-4HSxDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ccURrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-DO431D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-uPrBak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-sj9RQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-GAafmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-IrVyGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:16 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)) 05:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Mdtm3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:19 INFO - Downloading blessings-1.6.tar.gz 05:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:19 INFO - Installing collected packages: blessings 05:38:19 INFO - Running setup.py install for blessings 05:38:19 INFO - Successfully installed blessings 05:38:19 INFO - Cleaning up... 05:38:19 INFO - Return code: 0 05:38:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:19 INFO - Reading from file tmpfile_stdout 05:38:19 INFO - Current package versions: 05:38:19 INFO - blessings == 1.6 05:38:19 INFO - blobuploader == 1.2.4 05:38:19 INFO - docopt == 0.6.1 05:38:19 INFO - functools32 == 3.2.3-2 05:38:19 INFO - jsonschema == 2.5.1 05:38:19 INFO - manifestparser == 1.1 05:38:19 INFO - mozInstall == 1.12 05:38:19 INFO - mozcrash == 0.17 05:38:19 INFO - mozdebug == 0.1 05:38:19 INFO - mozdevice == 0.48 05:38:19 INFO - mozfile == 1.2 05:38:19 INFO - mozhttpd == 0.7 05:38:19 INFO - mozinfo == 0.9 05:38:19 INFO - mozleak == 0.1 05:38:19 INFO - mozlog == 3.4 05:38:19 INFO - moznetwork == 0.27 05:38:19 INFO - mozprocess == 0.23 05:38:19 INFO - mozprofile == 0.28 05:38:19 INFO - mozrunner == 6.12 05:38:19 INFO - mozscreenshot == 0.1 05:38:19 INFO - mozsystemmonitor == 0.3 05:38:19 INFO - moztest == 0.7 05:38:19 INFO - mozversion == 1.4 05:38:19 INFO - psutil == 3.1.1 05:38:19 INFO - requests == 1.2.3 05:38:19 INFO - wsgiref == 0.1.2 05:38:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:19 INFO - 'HOME': '/Users/cltbld', 05:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:19 INFO - 'LOGNAME': 'cltbld', 05:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:19 INFO - 'MOZ_NO_REMOTE': '1', 05:38:19 INFO - 'NO_EM_RESTART': '1', 05:38:19 INFO - 'PAGER': '/bin/cat', 05:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:19 INFO - 'PWD': '/builds/slave/test', 05:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:19 INFO - 'SHELL': '/bin/bash', 05:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:19 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:19 INFO - 'USER': 'cltbld', 05:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:19 INFO - 'XPC_FLAGS': '0x0', 05:38:19 INFO - 'XPC_SERVICE_NAME': '0', 05:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 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)) 05:38:20 INFO - Cleaning up... 05:38:20 INFO - Return code: 0 05:38:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:20 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')]} 05:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:20 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')]} 05:38:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:20 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 0x10a1b4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb519d785b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, '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.evlNAuiIG0/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.e1iaKRAMFp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:38:20 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 05:38:20 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 05:38:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:20 INFO - 'HOME': '/Users/cltbld', 05:38:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:20 INFO - 'LOGNAME': 'cltbld', 05:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:20 INFO - 'MOZ_NO_REMOTE': '1', 05:38:20 INFO - 'NO_EM_RESTART': '1', 05:38:20 INFO - 'PAGER': '/bin/cat', 05:38:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:20 INFO - 'PWD': '/builds/slave/test', 05:38:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:20 INFO - 'SHELL': '/bin/bash', 05:38:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:20 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:20 INFO - 'USER': 'cltbld', 05:38:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:20 INFO - 'XPC_FLAGS': '0x0', 05:38:20 INFO - 'XPC_SERVICE_NAME': '0', 05:38:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:20 INFO - Cleaning up... 05:38:20 INFO - Return code: 0 05:38:20 INFO - Running post-action listener: _resource_record_post_action 05:38:20 INFO - Running post-action listener: _start_resource_monitoring 05:38:20 INFO - Starting resource monitoring. 05:38:20 INFO - [mozharness: 2016-10-26 12:38:20.507779Z] Finished create-virtualenv step (success) 05:38:20 INFO - [mozharness: 2016-10-26 12:38:20.508856Z] Running install step. 05:38:20 INFO - Running pre-action listener: _resource_record_pre_action 05:38:20 INFO - Running main action method: install 05:38:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:20 INFO - Reading from file tmpfile_stdout 05:38:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:20 INFO - Reading from file tmpfile_stdout 05:38:20 INFO - Output received: 05:38:20 INFO - Usage: mozinstall [options] installer 05:38:20 INFO - Options: 05:38:20 INFO - -h, --help show this help message and exit 05:38:20 INFO - -d DEST, --destination=DEST 05:38:20 INFO - Directory to install application into. [default: 05:38:20 INFO - "/builds/slave/test"] 05:38:20 INFO - --app=APP Application being installed. [default: firefox] 05:38:20 INFO - mkdir: /builds/slave/test/build/application 05:38:20 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'] 05:38:20 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 05:38:54 INFO - Reading from file tmpfile_stdout 05:38:54 INFO - Output received: 05:38:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:38:54 INFO - Running post-action listener: _resource_record_post_action 05:38:54 INFO - [mozharness: 2016-10-26 12:38:54.805905Z] Finished install step (success) 05:38:54 INFO - [mozharness: 2016-10-26 12:38:54.806045Z] Running run-tests step. 05:38:54 INFO - Running pre-action listener: _resource_record_pre_action 05:38:54 INFO - Running main action method: run_tests 05:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:54 INFO - 2016-10-26 05:38:54.820 screenresolution[1658:9651] starting screenresolution argv=screenresolution get 05:38:54 INFO - 2016-10-26 05:38:54.823 screenresolution[1658:9651] Display 0: 1600x1200x32@60 05:38:54 INFO - 2016-10-26 05:38:54.829 screenresolution[1659:9653] starting screenresolution argv=screenresolution list 05:38:54 INFO - Available Modes on Display 0 05:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:55 INFO - Intel Iris: 05:38:55 INFO - Chipset Model: Intel Iris 05:38:55 INFO - Type: GPU 05:38:55 INFO - Bus: Built-In 05:38:55 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:55 INFO - Vendor: Intel (0x8086) 05:38:55 INFO - Device ID: 0x0a2e 05:38:55 INFO - Revision ID: 0x0009 05:38:55 INFO - Displays: 05:38:55 INFO - Display: 05:38:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:55 INFO - Display Serial Number: 1600x1200 60 05:38:55 INFO - Main Display: Yes 05:38:55 INFO - Mirror: Off 05:38:55 INFO - Online: Yes 05:38:55 INFO - Rotation: Supported 05:38:55 INFO - Return code: 0 05:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:55 INFO - Python 2.7.3 05:38:55 INFO - Return code: 0 05:38:55 INFO - grabbing minidump binary from tooltool 05:38:55 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')]} 05:38:55 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 0x109fc8d30>, '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 0x109deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:55 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 05:38:55 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 05:38:55 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 05:38:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:55 INFO - Return code: 0 05:38:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:38:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:38:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:55 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/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 05:38:55 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/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --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 05:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.evlNAuiIG0/Render', 05:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:55 INFO - 'HOME': '/Users/cltbld', 05:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:55 INFO - 'LOGNAME': 'cltbld', 05:38:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:55 INFO - 'MOZ_NO_REMOTE': '1', 05:38:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:55 INFO - 'NO_EM_RESTART': '1', 05:38:55 INFO - 'PAGER': '/bin/cat', 05:38:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:55 INFO - 'PWD': '/builds/slave/test', 05:38:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:55 INFO - 'SHELL': '/bin/bash', 05:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1iaKRAMFp/Listeners', 05:38:55 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:55 INFO - 'USER': 'cltbld', 05:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:55 INFO - 'XPC_FLAGS': '0x0', 05:38:55 INFO - 'XPC_SERVICE_NAME': '0', 05:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:55 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/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 05:38:55 INFO - mozversion application_buildid: 20161025185822 05:38:55 INFO - mozversion application_changeset: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 05:38:55 INFO - mozversion application_display_name: Nightly 05:38:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:55 INFO - mozversion application_name: Firefox 05:38:55 INFO - mozversion application_remotingname: firefox 05:38:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:38:55 INFO - mozversion application_vendor: Mozilla 05:38:55 INFO - mozversion application_version: 52.0a1 05:38:55 INFO - mozversion platform_buildid: 20161025185822 05:38:55 INFO - mozversion platform_changeset: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 05:38:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:38:55 INFO - mozversion platform_version: 52.0a1 05:38:55 INFO - using testdate: 1477485535 05:38:55 INFO - actual date: 1477485535 05:38:55 INFO - starting webserver on 'localhost:49276' 05:38:55 INFO - SUITE-START | Running 2 tests 05:38:55 INFO - TEST-START | dromaeo_css 05:38:55 INFO - operating with platform_type : mac_ 05:38:55 INFO - Initialising browser for dromaeo_css test... 05:38:55 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpbnQGpy/profile http://localhost:49276/getInfo.html) 05:38:57 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 05:38:57 INFO - PROCESS | 1669 | colorDepth:24 05:38:57 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 05:38:57 INFO - PROCESS | 1669 | __metrics 05:38:57 INFO - PROCESS | 1669 | 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" 05:38:57 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:58 INFO - TEST-INFO | 1669: exit 0 05:38:58 INFO - Browser initialized. 05:38:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:58 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpbnQGpy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:10 INFO - PROCESS | 1672 | RSS: Main: 231313408 05:39:10 INFO - PROCESS | 1672 | 05:40:02 INFO - PROCESS | 1672 | 1477485602235 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] 05:42:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 05:42:17 INFO - PROCESS | 1672 | RSS: Main: 392368128 05:42:17 INFO - PROCESS | 1672 | 05:45:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 05:45:20 INFO - PROCESS | 1672 | RSS: Main: 378613760 05:45:20 INFO - PROCESS | 1672 | 05:48:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 05:48:23 INFO - PROCESS | 1672 | RSS: Main: 351363072 05:48:23 INFO - PROCESS | 1672 | 05:51:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 05:51:26 INFO - PROCESS | 1672 | RSS: Main: 346787840 05:51:26 INFO - PROCESS | 1672 | 05:54:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 05:54:30 INFO - PROCESS | 1672 | RSS: Main: 328151040 05:54:30 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 05:57:34 INFO - PROCESS | 1672 | RSS: Main: 381849600 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | __start_tp_report 05:57:34 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 05:57:34 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 05:57:34 INFO - PROCESS | 1672 | |i|pagename|runs| 05:57:34 INFO - PROCESS | 1672 | |0;dojo.html;3298.851551680174;3304.884329048484;3317.8175200363985;3327.3178998305348;3387.1193489692687;5484.972575137119;5551.722413879305;5642.069058605331;5648.350439699436;5663.207150998864;5552.250446189763;5593.300837395325;5606.467385598372;5615.494605485505;5617.4382561743805;5073.7463126843695;5108.2848401223855;5114.43741188469;5136.460671629481;5161.483851614846;5172.534471897528;5186.2220666900075;5192.091384007803;5230.032443997854;5242.7640756166065;5128.743562821844;5143.382794064718;5170.767315470813;5172.353455818023;5203.349581302337;4150.356694712309;5229.37247530295;5243.161094224944;5244.81643142492;5302.231176479428;5041.991601679642;5046.873828154326;5108.233764935289;5157.845264642029;5164.715940623264;5437.32033495813;5572.4706152915405;5587.525060369873;5609.242752228479;5619.943800561983;6151.661658608775;6156.322804491502;6157.107219453199;6166.938330616681;6171.413715697002;4469.888252793706;6054.697265136726;6116.571839971204;6194.039923811816;6204.968975155095;5671.914921276143;5727.885442291131;5739.426057394311;5743.798967036218;5811.709414529257;6246.451445817888;6279;6283.874322513524;6293.96853015732;6298.1812364392335;5845.912311315334;6080.513558915276;6090.31181562206;6102.1846897342175;6109.519831324367;6069.453749162508;6087.813966394619;6098.634081955097;6126.693665316806;6139.570230083851;5405.621606487508;5722.227499287543;6039.78860739872;6054.152418661391;6065.363136870653;6068.605540639845;6074.787382441594;6079.665618391031;6089.451949324582;6096.878062438726;4113.362428823537;4754.976225118852;4832.1543729847135;4838.516148385133;4867.026594681078;4367.388565600819;4421.712588681726;4436.511983681792;4438.201123797748;4447.2439685253585;5625.887482250332;5631.577631677641;5632.662040277596;5636.577256705764;5641.802536911188;6119.677000462697;6138.202033735586;6148.907766383508;6205.47253483459;6220.533459990519;5713.314402271671;5723.856903577361;5729.598928075078;5748.396418376094;5777.046787280052;4493.7977790999485;5218.869528261748;5278.129108697022;5284.920726189111;5291.232771248147;5199.376074871039;6212.627242365473;6239.0953311770445;6254.3745625438005;6268.216472940882;6173.351798061139;6179.84550386244;6181.227346581677;6191.102290167811;6194.752209911733;6099.359567245376;6172.105044768573;6229.906551401642;6231.034189700604;6260.499160067275;6025;6028.33688294296;6058.818235452767;6077.058056001327;6117.218487018656;6176.258848937977;6178.9382106180165;6184.969075154595;6192.102145189013;6198.535109866688;6017.2779266488305;6038.969805150946;6044.546659000681;6046.90929636047;6061.636301821904;5843.707814609337;5906.911396329145;5924.052151655715;5934.9109763354445;5968.313643931004;4410.985473341086;5840.678762668094;5893.292804863444;5893.998020336639;5907.763689452374;5930.970345148246;5942.1978032965;5959.1657167995645;5961.135635332939;5985.790497332572;5662.518685911744;5713.285839270091;5822.301323841166;5824.213731146241;5829.38791426911 05:57:34 INFO - PROCESS | 1672 | |1;ext.html;457.75052596334996;469.0103880811489;502.83155143027096;518.8028549151322;520.6797819341107;31123;31676.36647267056;40467.19065618686;41184.794076029575;41424.58575414245;38779.41830872539;42314;42328.7301380959;42371.57628423715;42595.787021064854;9433.57548910299;9625.759356016086;9643.758906027335;9645.276604254708;9648.276379271547;8549.871751923725;8765.167309105625;8775.25410340122;8857.689980850671;8859.778505537348;11282.774344513105;11543;11580.42097895106;11793.233439826385;11817.70455738609;11495.19533632646;11575.13186511015;11584.884151158549;11589.710257243552;11595.478203480863;7131.500794944356;8694.434861733967;11353.829692554618;11447.885521144764;11455.369954652491;31497;31687;31925.840370797996;38246.42630360547;39294.60705392938;3424.229548351601;3534.6995505382074;3538.7345949053924;3566.6789988900864;3758.4926034985474;29686.257843553867;30616.693833061607;31074.406606810764;31162.37675246505;31480.527792083136;3137.905862824119;3231.5960504936884;3232.208109013307;3256.2836176041233;3376.74674399421;405780;406227;481851;481999.59000205126;483013;65642;66241;66275.67448651;66351.33648663452;66390.00414993778;35623.28753424917;36435;36498.27003459916;37785.43321850174;68659.65670171617;15323.61690957735;15497.5350739478;15512.689746205238;15513.301901414463;15558.455454059054;10981.835272470918;11037.448127593589;11057.834132488018;11078.723031924266;11090.223684342018;17169;17829.821701783076;17872.463826085237;20507.897460512602;20773.156965961345;8434.57827108642;8534.615942282611;8544.444611100258;8647.3946823723;8682.262007729287;889.8042430665373;894.6332003878379;919.9034101419388;1091.476091476096;1123.1015187849687;25524.617130743052;29697.257568560526;29899.25251868677;30603;30689.38621227563;976.9267304952157;1003.8042581696645;1106.5352551928208;1109.4730003248553;1140.2474366917795;14969.101853888802;15426.691466170614;15484.535463935874;15786.605334866721;15853.445129420415;646440;934981;940349;940745;940747;46546;56438;57001.14498282529;57017.42982570204;57078.85842283131;1079.1259080145107;1280.7246442015012;1284.9871501284867;1372.7185926884965;1388.2850332078988;28235.235559095257;29176.978805741695;30359.20049083945;30361.392772144874;31616;52830.67923301948;53449;53598.73200633972;53693;53828.730856345464;590149.6895217294;595087;595505;598444;601666;25331.60676162755;25749.613755794053;26598.601020985087;26871.193864184104;26915.59626605642;38260.61739382682;38519.614803851604;38897.80551097226;38945;46859.76570117127;35595;37625.05937351588;38437.423438647886;39716.60283397244;39772.801135995294;37875.24249514939;38083.809580951915;38350.39795224591;39817;39853.2029359417;38475.2304953905;38492.807535963264;38495.80752096222;38557.807210963765;40173.995650108984;30747.770089196183;33967.66032339645;34658;34990;35021.82489087538;22716.772832271465;25218.873905630353;25636.871815640803;25783.484330313662;25917.740822592288 05:57:34 INFO - PROCESS | 1672 | |2;jquery.html;314.43401876622073;377.36414142170435;383.9212961342925;393.0015869304586;404.85020542399315;19562;20130.89934550327;20450.07974641141;21039.47401314968;21166.682499762515;17454.12729363533;17752.911235443837;19035.143418546166;20042.29851955182;20797.37607871766;5153.201253805647;5361.356637203531;5393.352797664266;5433.510984011438;5454.972725136368;3909.3745000799877;5397.649152805055;5491.231227628115;5497.47773961475;5505.421930697279;4115.814788334517;4146.543880173179;5830.591858569902;6114.205153330061;6131.570790044699;5774.16274640179;6167.938320616804;6168.96915515424;6170.19787427636;6175.07373893915;6013.51891848651;6026.758929642809;6026.819195424145;6045.063015232646;6045.458408105948;13023.609291721168;13303.268320242383;13494;13664.521741738992;13695.863041369657;2152.1499007891953;2199.604071267171;2303.4932314890866;2370.454795397051;2866.3980564081567;8486.363522735819;9525.047495250488;10070.446125463097;10420.010099040579;10575.73560660982;1814.8094450082683;1820.1718218210683;1865.347128505026;1868.3132482201784;1868.6916658751425;115508.42245788801;118192;130437.6956230435;130804.6919530802;132019.33990329987;10027.849582256273;11042.944785276022;11257.21199516026;11451.312921224753;11558.30650160993;14797.852021479863;14810.62973425673;14818.555443336718;14825.555233343017;14836.703265934837;8006.639701213343;8061.395395345373;8189.058258300372;8207.138250483613;8313.459625124347;7952.960235198787;7960.203979602109;8105.513669179868;8131.268185863182;8172.550509721904;4453.376527286183;4538.887972446706;4542.205114105084;4621.976890115595;4645.46577143626;1847.9630407392046;1913.1295260656368;1921.1738952250666;1926.368794821771;1931;710.1549156503744;799.160881074869;860.3332417376583;884.6284560484612;899.8965119011267;21456;22056.669149962763;22150.113995440002;22164.002619882143;22205.888970555367;885.6811547842772;925.5834874306589;934.6495064350869;943.8820147481565;1027.2347101409498;10625.362478251329;11078.335299882034;11530.192886498033;11601.651950441501;11620.825687614693;499493;548501;565007;571440;578322;127258;127409;129667.35166324108;130412.3479382616;130738;913.1963871792913;917.1699611851285;943.0663642993387;944.1691311645845;948.4356807699407;19037.90481047586;19249;19249.422517324503;19259.711104333728;19304.227830886608;51567.968640627725;52214.73892630512;52303.476965229864;53160.46190022042;53428.1985770206;62604.17447819022;77016.84474732996;79580.60209698914;80973;81008.59495702483;7760.92239077602;8091.83816323682;8223.712170074019;8266.504009759194;8370.246677798783;10675.252732308974;12078.214916030782;12107.213031152947;13003.544875929298;13169.407376668265;10540.789184216119;10624.575016999233;10891.945540272565;10997.945010274898;11015.953484418967;10218.642347517802;10349.275550711378;10506.842397363893;10917.563297468012;10938.617148399768;9884.654037108667;10272.486375681336;10787.946060269649;11296.943515282372;11885;7942.523448593103;8031.397645176518;8243.340532757245;8449.831003379775;8452.281556067557;9516.33385662997;10169.084782369622;10618.575256989634;11039.613613523488;11079.279846809932 05:57:34 INFO - PROCESS | 1672 | |3;mootools.html;1520.3082597418177;1554.3627112883717;1754.6490701859632;1848.9722654160182;1909.2935613822879;7455.850882982337;8854.91145088549;8866.15771501708;8866.911330886689;8872.06843281455;7740.612969351538;7964.362850971923;8362.03836558796;8579.056303806576;8620.568971551413;2297.2074634251157;2346.002948746784;2348.144965477271;2351.1065794997876;2353.788159065684;2301.8964146613444;2324.8140148788143;2336.485973085927;2351.2828587280874;2359.4809141988735;2313.7570555091725;2338.2517594369724;2343.5781559319316;2347.85912845229;2352.0121548949396;2295.0934380919443;2312.7571604981486;2319.3158018384534;2319.420144963759;2324.825638077134;2300.7584203658707;2302.3668491164894;2304.8040916522114;2307.123293148593;2314.3866875278063;8650.956745216234;8718.651253949834;8762.474251544927;8788.956055219747;8793.428427152214;5167.534921857014;5329.040772660919;5334.706591137486;5337.919931201035;5338.786448542053;3251.9674803252137;3402.812845293508;3648.015035940308;3654.543182102237;3660.3411385950517;976.6435251133356;1034.7154532503546;1051.2746205118444;1082.1342925659442;1087.314991555323;11226.887731122748;11542.36516991565;11548.307101573848;11767.23512971654;11796.52813887452;4383.978080109643;4496.437945256843;4567.2464043432465;4618.976905115453;4646;8994.010658827523;9006.909930900738;9010.963739169949;9013;9058.32062595308;5264.236685680631;5271.209318602164;5272.393674727378;5277.419483856773;5278.9208161877605;4166.854160104443;4212.895643686642;4928.926066109011;4971.552560269593;4977.925331120036;3642.9817850910576;3665.743397962117;3739.6627108057464;3771.4908487354405;3787.299349620329;1122.6800361896983;1127.8590176227972;1136.3920302638048;1148.3052753084264;1152.2970987697497;790.4624855098588;820.9014918209853;901.2474583722665;904.3669431398048;915.2174890468686;4087.89780255496;5614.382417934024;5751.827445176651;5814.854628634318;5984.31180414258;407.69219239474006;414.6620504288995;419.19514532098447;436.960673539383;443.52542779226565;3742.3263812513464;3880.650741433228;3970.781607011642;3998.04047028711;4006.238814625212;10309.53607087683;10595.94702026485;10692.839607405895;10702.25084244111;10708.571657133627;9696.418214906987;9969.30214884951;10597.364158150536;10655.467226638792;10846.728831779112;8040.959795200986;8068.314193293636;8234.464759790475;8321.583920803936;8326.50040997542;5798.420157984167;5843.2695913010875;5865.442782935615;5919.260092488439;5924.615784319486;9739.415635061636;9875.851862222216;9886.851697224403;9891.653792117242;9991;9785.951070244602;10054.49727513636;10069.979006298228;10147.7463063424;10205.979402060031;6310.977362902685;6782.762603308861;7316.451266154953;7402.814929626802;7574.242575742602;8364.289035432057;8453.365997550085;8456.534890581077;8459.830803383775;8471.32229421657;7200.747973820891;7294.088238970129;7355.77932662021;7430.294122058396;7540.7197868924995;7252.38354739853;7375.11498620169;7397.482176247611;7415.147258065176;7453.962730186315;6842.24735279109;6963.7562685305775;6964.477664175106;6966;6972.546784458994;4879.194932836042;4932.081909565212;4977.129002424489;5029.1953287473825;5189.9879523492555;4230.492340918986;4987.152184128634;5068.923966140437;5105.617078719037;5178.456262092426 05:57:34 INFO - PROCESS | 1672 | |4;prototype.html;301.27092436304144;301.3490859742955;309.1005174940922;313.52500961044007;316.6580093499021;5517.668939863606;5537.58468114891;5549.640338117162;5559.9444005559935;5580.804671836487;5088.720120393377;5338.570950217651;5353.652012619177;5354.785808567653;5486.177073438987;4003.619656132663;4126.484189476316;4147.647449966741;4171.165766846628;4214.431051807997;3601.873934412296;4161.0429601191745;4166.916661666764;4178.164367126572;4184.1840841035855;3213.293075523381;4005.5994400560003;4133.586641335873;4169.041120542277;4171.833126674929;4120.587941205886;4165.729227600196;4169.958300416987;4200.369944508333;4250.404943307939;3037.681043490446;3572.374834403995;3603.9099022524383;3612.4581312803025;3634.128423861713;4054.6888273684012;4594.310853371988;4861.197902346144;5266.657667251617;5324.254604355393;3937.5471820740695;4090.5909409058854;4143.295639741251;4617.976910115428;4720.858374248778;4817.542333478314;5754.1944127822135;5774.307083150007;5784.710764461802;5787.50806181475;3341.1480072581335;3390.8304584770663;3411.4030044742317;3555.826577229508;3894.435306880515;5294.338207724035;5686.516646085088;5733.541316694696;5775.537956963432;6030.577859549833;3248.902532924016;3875.8255878485534;3946.9210615787524;3947.4276229947072;3947.8945895149404;3448.430918965387;3948.998760941705;3997.6801855851463;4019.577944315863;4035.8991025224605;4558.794854231567;4637.628989680885;4651;4691.835785747482;4753;5328.333958255218;5772;5777.104548794943;5783.942160578425;5790.481140703723;1044.9634262800917;1278.7826069568196;1377.7944298738603;1378.1593228130832;1382.1776043254247;772.6600295869799;784.0395515493589;804.8108694456798;809.4455298120806;836.8869403693074;4515;4532.614727748179;4538.909221815546;4545.727076504912;4651.790669419885;4230.9365359519015;4327.836081959021;4730.273450190655;4792.664513484093;4929.077659712715;3626.492291079251;3868.7872167031082;4451.241759505042;4516.774161291923;4541.68208225421;2317.154238702861;2589.7669209770834;2612.8301660392012;2628.6188502667005;2636.327736427199;4304.169289332496;4710.905781884411;4712.256465107932;4971.204607262821;5096.769681164625;3643.825767410284;4687.000319795334;4788.592969597623;4838.137317132859;4891.584215341664;4202.768847713344;4353.258696521417;4761.452432970253;5072.746362681777;5136.486351364908;3882.3987928087686;3943.093088589583;4259.850905218303;4329.891752706208;4912.582430493305;4277.080427708058;4350.804213810323;4496.977515112403;4624.745638989888;4848.9757551212015;5432.456754324536;5726.770929162787;6217.595856269328;6280.026595877646;6287.559870808999;3518.401871681769;3937.5274967004143;3949.3681011038097;3992.963588569792;4010.6791456682813;4587.6788624795945;5035.521625445724;5635.168570214779;6115;6130.601510901777;5152.922706159486;5595.692236927008;5707.886962042362;5851.882962340815;5873.882522349443;4831.801530303633;5806.970965145148;5808.680522571299;5810.767569297181;5848.356680765198;5345.518903298722;5709.885802284014;5721.141828725725;5750.25246717924;5751.683657398883;4522.525134860793;5546.533416614616;5721.771129154788;5737.454941780524;5786.247787787561;4937.407511098691;4945.357103576512;5641.492265696107;5662.716864156858;5662.943370566241 05:57:34 INFO - PROCESS | 1672 | |5;yui.html;716.0176723475366;718.6730037832785;722.4870342057139;755.6335177438941;839.0979696825912;14290.356933937972;14989.025713328627;14994.375984202748;14998.700025999473;15263.313150908209;13610.31948402581;13697.794533082011;13701.862981370208;13720.45118195271;13721.588352349421;676.1345477788415;709.8118998465411;710.5168485429906;712.8146681862701;873.7029410000598;708.9113860767404;713.3793599568382;841.2302742990157;883.3021912688968;888.2361169394316;929.8930622978343;935.8034812689343;946.8343039968013;947.90520947905;948.3077353531926;934.2339281788902;935.2985261054175;937.6483818568037;937.6530683647026;937.6624415210518;531.4207513809965;557.5066066531109;700.737223541172;701.7087908517959;703.0614130136274;13625.727485450236;14090;14163.929180354033;14407.207603581799;14423.350949207312;11746.941265293704;13743.03798734089;14141.929290353482;14251.216183109926;14275.643108922257;11506;11605.303681779036;11634.243774154653;11663.94168029163;11758.470868810924;3699.264424296382;3725.2921944830396;3773.64150405711;3785.6403641653997;3798.335291324035;87666.5616671919;87727;88389.11610883937;88874.55562722145;89625;10690;10821.026107650348;10896;10992.945035274772;11161.83257251142;18044.54888627795;21770.564588708454;21785.673214901788;21860.45348866291;21875.234366797085;7668.1181664109345;7719.65261563231;7758.9612051939375;7797.376209903194;7918.683252669944;4281.785910704452;4338.566143385699;4376.212281789309;4458.175237581057;4539.273716205457;3241.3517296540786;3380.7295416366633;3442.5868895732674;3544.3442963051916;3551.6448355164794;594.6996766632823;606.8088552106161;620.7827260458803;624.9406306400884;657.2080642825384;627.8869803435425;644.5101722690789;656.5666660004374;664.7806223946183;676.9686516792489;8293.917060829435;8912.509811960404;9011.27909767217;9053.637854485747;9064.864027039599;499.9925001124986;598.637824116412;643.6299128001418;652.9422335815914;659.6009414304373;4301.698881078357;4429.512753597101;4606.539346065367;4779.832705855279;4820.3492528508905;109994.95078262882;116258.41870790592;118601.40699296448;118886.40556797334;123739;95234.04765952252;95266;95619.52190239144;96636;98592.59148155346;79704.60147699225;86490.56754716186;87494.56252718695;89076;89645.72616887401;9262.907370926205;9637.544103042595;9687.012747642542;9699.463736351086;9718.558045536442;54797.726011371284;56138;56285.8742825133;56301.71849140728;56801;75694.48611027638;80098.59950700209;81676.18323816924;83315.333693327;85022;12493.063020273334;12600.226816020358;12734.981201504042;12842.972562194815;12999.935000324938;12224.877751222373;13719.45122195101;14140;14379;14412.063215890934;11363.37501437429;13101;13261.668458288234;13580.388882500114;13661.38523766413;13055.804162937753;13262.67373262666;13517;13681.863181368059;13987.580372588838;10902.672919812418;11173.88826111761;13213.339333033502;13249.668758281121;13663.590092297247;10805.580915333612;11042.227044106836;11185.888141118485;11293.322400655987;11554.826677600011;10397.220208484243;10418.322809017389;11019.393933333744;11235.157363197957;11439.370834604177 05:57:34 INFO - PROCESS | 1672 | __end_tp_report 05:57:34 INFO - PROCESS | 1672 | __start_cc_report 05:57:34 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1554 05:57:34 INFO - PROCESS | 1672 | __end_cc_report 05:57:34 INFO - PROCESS | 1672 | __startTimestamp1477486654384__endTimestamp 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | ------- Summary: start ------- 05:57:34 INFO - PROCESS | 1672 | Number of tests: 6 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5620.71 Median:5822.30 stddev:654.53 (11.2%) stddev-sans-first:630.68 05:57:34 INFO - PROCESS | 1672 | Values: 3298.9 3304.9 3317.8 3327.3 3387.1 5485.0 5551.7 5642.1 5648.4 5663.2 5552.3 5593.3 5606.5 5615.5 5617.4 5073.7 5108.3 5114.4 5136.5 5161.5 5172.5 5186.2 5192.1 5230.0 5242.8 5128.7 5143.4 5170.8 5172.4 5203.3 4150.4 5229.4 5243.2 5244.8 5302.2 5042.0 5046.9 5108.2 5157.8 5164.7 5437.3 5572.5 5587.5 5609.2 5619.9 6151.7 6156.3 6157.1 6166.9 6171.4 4469.9 6054.7 6116.6 6194.0 6205.0 5671.9 5727.9 5739.4 5743.8 5811.7 6246.5 6279.0 6283.9 6294.0 6298.2 5845.9 6080.5 6090.3 6102.2 6109.5 6069.5 6087.8 6098.6 6126.7 6139.6 5405.6 5722.2 6039.8 6054.2 6065.4 6068.6 6074.8 6079.7 6089.5 6096.9 4113.4 4755.0 4832.2 4838.5 4867.0 4367.4 4421.7 4436.5 4438.2 4447.2 5625.9 5631.6 5632.7 5636.6 5641.8 6119.7 6138.2 6148.9 6205.5 6220.5 5713.3 5723.9 5729.6 5748.4 5777.0 4493.8 5218.9 5278.1 5284.9 5291.2 5199.4 6212.6 6239.1 6254.4 6268.2 6173.4 6179.8 6181.2 6191.1 6194.8 6099.4 6172.1 6229.9 6231.0 6260.5 6025.0 6028.3 6058.8 6077.1 6117.2 6176.3 6178.9 6185.0 6192.1 6198.5 6017.3 6039.0 6044.5 6046.9 6061.6 5843.7 5906.9 5924.1 5934.9 5968.3 4411.0 5840.7 5893.3 5894.0 5907.8 5931.0 5942.2 5959.2 5961.1 5985.8 5662.5 5713.3 5822.3 5824.2 5829.4 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:75624.99 Median:36466.64 stddev:181075.83 (496.6%) stddev-sans-first:181495.85 05:57:34 INFO - PROCESS | 1672 | Values: 457.8 469.0 502.8 518.8 520.7 31123.0 31676.4 40467.2 41184.8 41424.6 38779.4 42314.0 42328.7 42371.6 42595.8 9433.6 9625.8 9643.8 9645.3 9648.3 8549.9 8765.2 8775.3 8857.7 8859.8 11282.8 11543.0 11580.4 11793.2 11817.7 11495.2 11575.1 11584.9 11589.7 11595.5 7131.5 8694.4 11353.8 11447.9 11455.4 31497.0 31687.0 31925.8 38246.4 39294.6 3424.2 3534.7 3538.7 3566.7 3758.5 29686.3 30616.7 31074.4 31162.4 31480.5 3137.9 3231.6 3232.2 3256.3 3376.7 405780.0 406227.0 481851.0 481999.6 483013.0 65642.0 66241.0 66275.7 66351.3 66390.0 35623.3 36435.0 36498.3 37785.4 68659.7 15323.6 15497.5 15512.7 15513.3 15558.5 10981.8 11037.4 11057.8 11078.7 11090.2 17169.0 17829.8 17872.5 20507.9 20773.2 8434.6 8534.6 8544.4 8647.4 8682.3 889.8 894.6 919.9 1091.5 1123.1 25524.6 29697.3 29899.3 30603.0 30689.4 976.9 1003.8 1106.5 1109.5 1140.2 14969.1 15426.7 15484.5 15786.6 15853.4 646440.0 934981.0 940349.0 940745.0 940747.0 46546.0 56438.0 57001.1 57017.4 57078.9 1079.1 1280.7 1285.0 1372.7 1388.3 28235.2 29177.0 30359.2 30361.4 31616.0 52830.7 53449.0 53598.7 53693.0 53828.7 590149.7 595087.0 595505.0 598444.0 601666.0 25331.6 25749.6 26598.6 26871.2 26915.6 38260.6 38519.6 38897.8 38945.0 46859.8 35595.0 37625.1 38437.4 39716.6 39772.8 37875.2 38083.8 38350.4 39817.0 39853.2 38475.2 38492.8 38495.8 38557.8 40174.0 30747.8 33967.7 34658.0 34990.0 35021.8 22716.8 25218.9 25636.9 25783.5 25917.7 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:33504.03 Median:2618.43 stddev:93087.60 (3555.1%) stddev-sans-first:93315.38 05:57:34 INFO - PROCESS | 1672 | Values: 314.4 377.4 383.9 393.0 404.9 19562.0 20130.9 20450.1 21039.5 21166.7 17454.1 17752.9 19035.1 20042.3 20797.4 5153.2 5361.4 5393.4 5433.5 5455.0 3909.4 5397.6 5491.2 5497.5 5505.4 4115.8 4146.5 5830.6 6114.2 6131.6 5774.2 6167.9 6169.0 6170.2 6175.1 6013.5 6026.8 6026.8 6045.1 6045.5 13023.6 13303.3 13494.0 13664.5 13695.9 2152.1 2199.6 2303.5 2370.5 2866.4 8486.4 9525.0 10070.4 10420.0 10575.7 1814.8 1820.2 1865.3 1868.3 1868.7 115508.4 118192.0 130437.7 130804.7 132019.3 10027.8 11042.9 11257.2 11451.3 11558.3 14797.9 14810.6 14818.6 14825.6 14836.7 8006.6 8061.4 8189.1 8207.1 8313.5 7953.0 7960.2 8105.5 8131.3 8172.6 4453.4 4538.9 4542.2 4622.0 4645.5 1848.0 1913.1 1921.2 1926.4 1931.0 710.2 799.2 860.3 884.6 899.9 21456.0 22056.7 22150.1 22164.0 22205.9 885.7 925.6 934.6 943.9 1027.2 10625.4 11078.3 11530.2 11601.7 11620.8 499493.0 548501.0 565007.0 571440.0 578322.0 127258.0 127409.0 129667.4 130412.3 130738.0 913.2 917.2 943.1 944.2 948.4 19037.9 19249.0 19249.4 19259.7 19304.2 51568.0 52214.7 52303.5 53160.5 53428.2 62604.2 77016.8 79580.6 80973.0 81008.6 7760.9 8091.8 8223.7 8266.5 8370.2 10675.3 12078.2 12107.2 13003.5 13169.4 10540.8 10624.6 10891.9 10997.9 11016.0 10218.6 10349.3 10506.8 10917.6 10938.6 9884.7 10272.5 10787.9 11296.9 11885.0 7942.5 8031.4 8243.3 8449.8 8452.3 9516.3 10169.1 10618.6 11039.6 11079.3 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5595.95 Median:4982.54 stddev:3162.87 (63.5%) stddev-sans-first:3156.91 05:57:34 INFO - PROCESS | 1672 | Values: 1520.3 1554.4 1754.6 1849.0 1909.3 7455.9 8854.9 8866.2 8866.9 8872.1 7740.6 7964.4 8362.0 8579.1 8620.6 2297.2 2346.0 2348.1 2351.1 2353.8 2301.9 2324.8 2336.5 2351.3 2359.5 2313.8 2338.3 2343.6 2347.9 2352.0 2295.1 2312.8 2319.3 2319.4 2324.8 2300.8 2302.4 2304.8 2307.1 2314.4 8651.0 8718.7 8762.5 8789.0 8793.4 5167.5 5329.0 5334.7 5337.9 5338.8 3252.0 3402.8 3648.0 3654.5 3660.3 976.6 1034.7 1051.3 1082.1 1087.3 11226.9 11542.4 11548.3 11767.2 11796.5 4384.0 4496.4 4567.2 4619.0 4646.0 8994.0 9006.9 9011.0 9013.0 9058.3 5264.2 5271.2 5272.4 5277.4 5278.9 4166.9 4212.9 4928.9 4971.6 4977.9 3643.0 3665.7 3739.7 3771.5 3787.3 1122.7 1127.9 1136.4 1148.3 1152.3 790.5 820.9 901.2 904.4 915.2 4087.9 5614.4 5751.8 5814.9 5984.3 407.7 414.7 419.2 437.0 443.5 3742.3 3880.7 3970.8 3998.0 4006.2 10309.5 10595.9 10692.8 10702.3 10708.6 9696.4 9969.3 10597.4 10655.5 10846.7 8041.0 8068.3 8234.5 8321.6 8326.5 5798.4 5843.3 5865.4 5919.3 5924.6 9739.4 9875.9 9886.9 9891.7 9991.0 9786.0 10054.5 10070.0 10147.7 10206.0 6311.0 6782.8 7316.5 7402.8 7574.2 8364.3 8453.4 8456.5 8459.8 8471.3 7200.7 7294.1 7355.8 7430.3 7540.7 7252.4 7375.1 7397.5 7415.1 7454.0 6842.2 6963.8 6964.5 6966.0 6972.5 4879.2 4932.1 4977.1 5029.2 5190.0 4230.5 4987.2 5068.9 5105.6 5178.5 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4358.18 Median:4621.36 stddev:1363.75 (29.5%) stddev-sans-first:1333.15 05:57:34 INFO - PROCESS | 1672 | Values: 301.3 301.3 309.1 313.5 316.7 5517.7 5537.6 5549.6 5559.9 5580.8 5088.7 5338.6 5353.7 5354.8 5486.2 4003.6 4126.5 4147.6 4171.2 4214.4 3601.9 4161.0 4166.9 4178.2 4184.2 3213.3 4005.6 4133.6 4169.0 4171.8 4120.6 4165.7 4170.0 4200.4 4250.4 3037.7 3572.4 3603.9 3612.5 3634.1 4054.7 4594.3 4861.2 5266.7 5324.3 3937.5 4090.6 4143.3 4618.0 4720.9 4817.5 5754.2 5774.3 5784.7 5787.5 3341.1 3390.8 3411.4 3555.8 3894.4 5294.3 5686.5 5733.5 5775.5 6030.6 3248.9 3875.8 3946.9 3947.4 3947.9 3448.4 3949.0 3997.7 4019.6 4035.9 4558.8 4637.6 4651.0 4691.8 4753.0 5328.3 5772.0 5777.1 5783.9 5790.5 1045.0 1278.8 1377.8 1378.2 1382.2 772.7 784.0 804.8 809.4 836.9 4515.0 4532.6 4538.9 4545.7 4651.8 4230.9 4327.8 4730.3 4792.7 4929.1 3626.5 3868.8 4451.2 4516.8 4541.7 2317.2 2589.8 2612.8 2628.6 2636.3 4304.2 4710.9 4712.3 4971.2 5096.8 3643.8 4687.0 4788.6 4838.1 4891.6 4202.8 4353.3 4761.5 5072.7 5136.5 3882.4 3943.1 4259.9 4329.9 4912.6 4277.1 4350.8 4497.0 4624.7 4849.0 5432.5 5726.8 6217.6 6280.0 6287.6 3518.4 3937.5 3949.4 3993.0 4010.7 4587.7 5035.5 5635.2 6115.0 6130.6 5152.9 5595.7 5707.9 5851.9 5873.9 4831.8 5807.0 5808.7 5810.8 5848.4 5345.5 5709.9 5721.1 5750.3 5751.7 4522.5 5546.5 5721.8 5737.5 5786.2 4937.4 4945.4 5641.5 5662.7 5662.9 05:57:34 INFO - PROCESS | 1672 | 05:57:34 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:21181.97 Median:4498.72 stddev:31225.04 (694.1%) stddev-sans-first:31274.82 05:57:34 INFO - PROCESS | 1672 | Values: 716.0 718.7 722.5 755.6 839.1 14290.4 14989.0 14994.4 14998.7 15263.3 13610.3 13697.8 13701.9 13720.5 13721.6 676.1 709.8 710.5 712.8 873.7 708.9 713.4 841.2 883.3 888.2 929.9 935.8 946.8 947.9 948.3 934.2 935.3 937.6 937.7 937.7 531.4 557.5 700.7 701.7 703.1 13625.7 14090.0 14163.9 14407.2 14423.4 11746.9 13743.0 14141.9 14251.2 14275.6 11506.0 11605.3 11634.2 11663.9 11758.5 3699.3 3725.3 3773.6 3785.6 3798.3 87666.6 87727.0 88389.1 88874.6 89625.0 10690.0 10821.0 10896.0 10992.9 11161.8 18044.5 21770.6 21785.7 21860.5 21875.2 7668.1 7719.7 7759.0 7797.4 7918.7 4281.8 4338.6 4376.2 4458.2 4539.3 3241.4 3380.7 3442.6 3544.3 3551.6 594.7 606.8 620.8 624.9 657.2 627.9 644.5 656.6 664.8 677.0 8293.9 8912.5 9011.3 9053.6 9064.9 500.0 598.6 643.6 652.9 659.6 4301.7 4429.5 4606.5 4779.8 4820.3 109995.0 116258.4 118601.4 118886.4 123739.0 95234.0 95266.0 95619.5 96636.0 98592.6 79704.6 86490.6 87494.6 89076.0 89645.7 9262.9 9637.5 9687.0 9699.5 9718.6 54797.7 56138.0 56285.9 56301.7 56801.0 75694.5 80098.6 81676.2 83315.3 85022.0 12493.1 12600.2 12735.0 12843.0 12999.9 12224.9 13719.5 14140.0 14379.0 14412.1 11363.4 13101.0 13261.7 13580.4 13661.4 13055.8 13262.7 13517.0 13681.9 13987.6 10902.7 11173.9 13213.3 13249.7 13663.6 10805.6 11042.2 11185.9 11293.3 11554.8 10397.2 10418.3 11019.4 11235.2 11439.4 05:57:34 INFO - PROCESS | 1672 | -------- Summary: end -------- 05:57:34 INFO - PROCESS | 1672 | 05:57:35 INFO - TEST-INFO | 1672: exit 0 05:57:35 INFO - TEST-OK | dromaeo_css | took 1119621ms 05:57:35 INFO - TEST-START | kraken 05:57:35 INFO - operating with platform_type : mac_ 05:57:35 INFO - Initialising browser for kraken test... 05:57:35 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpMTCvdk/profile http://localhost:49276/getInfo.html) 05:57:37 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 05:57:37 INFO - PROCESS | 1677 | colorDepth:24 05:57:37 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 05:57:37 INFO - PROCESS | 1677 | __metrics 05:57:37 INFO - PROCESS | 1677 | 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" 05:57:37 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:38 INFO - TEST-INFO | 1677: exit 0 05:57:38 INFO - Browser initialized. 05:57:38 INFO - Running cycle 1/1 for kraken test... 05:57:38 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpMTCvdk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:50 INFO - PROCESS | 1678 | RSS: Main: 231268352 05:57:50 INFO - PROCESS | 1678 | 05:57:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:54 INFO - PROCESS | 1678 | RSS: Main: 289415168 05:57:54 INFO - PROCESS | 1678 | 05:57:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 05:57:57 INFO - PROCESS | 1678 | RSS: Main: 290246656 05:57:57 INFO - PROCESS | 1678 | 05:58:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 05:58:01 INFO - PROCESS | 1678 | RSS: Main: 382730240 05:58:01 INFO - PROCESS | 1678 | 05:58:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 05:58:04 INFO - PROCESS | 1678 | RSS: Main: 284196864 05:58:04 INFO - PROCESS | 1678 | 05:58:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:58:06 INFO - PROCESS | 1678 | RSS: Main: 288415744 05:58:06 INFO - PROCESS | 1678 | 05:58:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 05:58:10 INFO - PROCESS | 1678 | RSS: Main: 325312512 05:58:10 INFO - PROCESS | 1678 | 05:58:13 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 05:58:13 INFO - PROCESS | 1678 | RSS: Main: 327770112 05:58:13 INFO - PROCESS | 1678 | 05:58:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 05:58:16 INFO - PROCESS | 1678 | RSS: Main: 325107712 05:58:16 INFO - PROCESS | 1678 | 05:58:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:58:19 INFO - PROCESS | 1678 | RSS: Main: 301887488 05:58:19 INFO - PROCESS | 1678 | 05:58:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:58:21 INFO - PROCESS | 1678 | RSS: Main: 306085888 05:58:21 INFO - PROCESS | 1678 | 05:58:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:58:24 INFO - PROCESS | 1678 | RSS: Main: 308871168 05:58:24 INFO - PROCESS | 1678 | 05:58:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:58:28 INFO - PROCESS | 1678 | RSS: Main: 318820352 05:58:28 INFO - PROCESS | 1678 | 05:58:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:31 INFO - PROCESS | 1678 | RSS: Main: 318402560 05:58:31 INFO - PROCESS | 1678 | 05:58:33 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 05:58:34 INFO - PROCESS | 1678 | RSS: Main: 362242048 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | __start_tp_report 05:58:34 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 05:58:34 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 05:58:34 INFO - PROCESS | 1678 | |i|pagename|runs| 05:58:34 INFO - PROCESS | 1678 | |0;ai-astar;103;113;112;112;112;112;112;113;112;111 05:58:34 INFO - PROCESS | 1678 | |1;audio-beat-detection;173;100;129;185;143;112;112;115;141;111 05:58:34 INFO - PROCESS | 1678 | |2;audio-dft;183;183;183;182;183;183;187;184;181;181 05:58:34 INFO - PROCESS | 1678 | |3;audio-fft;79;59;59;88;61;60;62;57;82;58 05:58:34 INFO - PROCESS | 1678 | |4;audio-oscillator;87;71;91;69;67;68;91;68;68;91 05:58:34 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;84;87;83;87;86;88;85;87;84 05:58:34 INFO - PROCESS | 1678 | |6;imaging-darkroom;96;97;96;95;96;97;95;117;161;100 05:58:34 INFO - PROCESS | 1678 | |7;imaging-desaturate;81;83;81;78;82;81;85;85;83;83 05:58:34 INFO - PROCESS | 1678 | |8;json-parse-financial;57;56;53;54;56;56;55;54;55;54 05:58:34 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;64;76;76;75;71;69;69;71;69;69 05:58:34 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;86;91;78;88;98;106;109;106;90;87 05:58:34 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;159;157;147;152;156;145;149;147;179;156 05:58:34 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;170;169;174;171;173;171;172;230;172;172 05:58:34 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;63;90;87;69;69;68;68;69;72;78 05:58:34 INFO - PROCESS | 1678 | __end_tp_report 05:58:34 INFO - PROCESS | 1678 | __start_cc_report 05:58:34 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2027 05:58:34 INFO - PROCESS | 1678 | __end_cc_report 05:58:34 INFO - PROCESS | 1678 | __startTimestamp1477486714047__endTimestamp 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | ------- Summary: start ------- 05:58:34 INFO - PROCESS | 1678 | Number of tests: 14 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.94 (2.6%) stddev-sans-first:0.60 05:58:34 INFO - PROCESS | 1678 | Values: 103.0 113.0 112.0 112.0 112.0 112.0 112.0 113.0 112.0 111.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:135.00 stddev:28.39 (21.0%) stddev-sans-first:25.97 05:58:34 INFO - PROCESS | 1678 | Values: 173.0 100.0 129.0 185.0 143.0 112.0 112.0 115.0 141.0 111.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.70 (0.9%) stddev-sans-first:1.80 05:58:34 INFO - PROCESS | 1678 | Values: 183.0 183.0 183.0 182.0 183.0 183.0 187.0 184.0 181.0 181.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:66.50 Median:61.50 stddev:11.67 (19.0%) stddev-sans-first:11.47 05:58:34 INFO - PROCESS | 1678 | Values: 79.0 59.0 59.0 88.0 61.0 60.0 62.0 57.0 82.0 58.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:11.21 (14.2%) stddev-sans-first:11.30 05:58:34 INFO - PROCESS | 1678 | Values: 87.0 71.0 91.0 69.0 67.0 68.0 91.0 68.0 68.0 91.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.69 (1.9%) stddev-sans-first:1.73 05:58:34 INFO - PROCESS | 1678 | Values: 87.0 84.0 87.0 83.0 87.0 86.0 88.0 85.0 87.0 84.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:96.00 stddev:20.75 (21.6%) stddev-sans-first:21.75 05:58:34 INFO - PROCESS | 1678 | Values: 96.0 97.0 96.0 95.0 96.0 97.0 95.0 117.0 161.0 100.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:82.20 Median:83.00 stddev:2.10 (2.5%) stddev-sans-first:2.18 05:58:34 INFO - PROCESS | 1678 | Values: 81.0 83.0 81.0 78.0 82.0 81.0 85.0 85.0 83.0 83.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.25 (2.2%) stddev-sans-first:1.09 05:58:34 INFO - PROCESS | 1678 | Values: 57.0 56.0 53.0 54.0 56.0 56.0 55.0 54.0 55.0 54.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.81 (5.4%) stddev-sans-first:3.12 05:58:34 INFO - PROCESS | 1678 | Values: 64.0 76.0 76.0 75.0 71.0 69.0 69.0 71.0 69.0 69.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:87.50 stddev:10.32 (11.8%) stddev-sans-first:10.54 05:58:34 INFO - PROCESS | 1678 | Values: 86.0 91.0 78.0 88.0 98.0 106.0 109.0 106.0 90.0 87.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:156.00 stddev:9.83 (6.3%) stddev-sans-first:10.31 05:58:34 INFO - PROCESS | 1678 | Values: 159.0 157.0 147.0 152.0 156.0 145.0 149.0 147.0 179.0 156.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:172.00 stddev:18.54 (10.8%) stddev-sans-first:19.47 05:58:34 INFO - PROCESS | 1678 | Values: 170.0 169.0 174.0 171.0 173.0 171.0 172.0 230.0 172.0 172.0 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:70.50 stddev:8.87 (12.6%) stddev-sans-first:8.59 05:58:34 INFO - PROCESS | 1678 | Values: 63.0 90.0 87.0 69.0 69.0 68.0 68.0 69.0 72.0 78.0 05:58:34 INFO - PROCESS | 1678 | -------- Summary: end -------- 05:58:34 INFO - PROCESS | 1678 | 05:58:34 INFO - TEST-INFO | 1678: exit 0 05:58:34 INFO - TEST-OK | kraken | took 59921ms 05:58:34 INFO - SUITE-END | took 1179s 05:58:35 INFO - Completed test suite (00:19:39) 05:58:35 INFO - javascript benchmark 05:58:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.851551680174, 3304.884329048484, 3317.8175200363985, 3327.3178998305348, 3387.1193489692687, 5484.972575137119, 5551.722413879305, 5642.069058605331, 5648.350439699436, 5663.207150998864, 5552.250446189763, 5593.300837395325, 5606.467385598372, 5615.494605485505, 5617.4382561743805, 5073.7463126843695, 5108.2848401223855, 5114.43741188469, 5136.460671629481, 5161.483851614846, 5172.534471897528, 5186.2220666900075, 5192.091384007803, 5230.032443997854, 5242.7640756166065, 5128.743562821844, 5143.382794064718, 5170.767315470813, 5172.353455818023, 5203.349581302337, 4150.356694712309, 5229.37247530295, 5243.161094224944, 5244.81643142492, 5302.231176479428, 5041.991601679642, 5046.873828154326, 5108.233764935289, 5157.845264642029, 5164.715940623264, 5437.32033495813, 5572.4706152915405, 5587.525060369873, 5609.242752228479, 5619.943800561983, 6151.661658608775, 6156.322804491502, 6157.107219453199, 6166.938330616681, 6171.413715697002, 4469.888252793706, 6054.697265136726, 6116.571839971204, 6194.039923811816, 6204.968975155095, 5671.914921276143, 5727.885442291131, 5739.426057394311, 5743.798967036218, 5811.709414529257, 6246.451445817888, 6279.0, 6283.874322513524, 6293.96853015732, 6298.1812364392335, 5845.912311315334, 6080.513558915276, 6090.31181562206, 6102.1846897342175, 6109.519831324367, 6069.453749162508, 6087.813966394619, 6098.634081955097, 6126.693665316806, 6139.570230083851, 5405.621606487508, 5722.227499287543, 6039.78860739872, 6054.152418661391, 6065.363136870653, 6068.605540639845, 6074.787382441594, 6079.665618391031, 6089.451949324582, 6096.878062438726, 4113.362428823537, 4754.976225118852, 4832.1543729847135, 4838.516148385133, 4867.026594681078, 4367.388565600819, 4421.712588681726, 4436.511983681792, 4438.201123797748, 4447.2439685253585, 5625.887482250332, 5631.577631677641, 5632.662040277596, 5636.577256705764, 5641.802536911188, 6119.677000462697, 6138.202033735586, 6148.907766383508, 6205.47253483459, 6220.533459990519, 5713.314402271671, 5723.856903577361, 5729.598928075078, 5748.396418376094, 5777.046787280052, 4493.7977790999485, 5218.869528261748, 5278.129108697022, 5284.920726189111, 5291.232771248147, 5199.376074871039, 6212.627242365473, 6239.0953311770445, 6254.3745625438005, 6268.216472940882, 6173.351798061139, 6179.84550386244, 6181.227346581677, 6191.102290167811, 6194.752209911733, 6099.359567245376, 6172.105044768573, 6229.906551401642, 6231.034189700604, 6260.499160067275, 6025.0, 6028.33688294296, 6058.818235452767, 6077.058056001327, 6117.218487018656, 6176.258848937977, 6178.9382106180165, 6184.969075154595, 6192.102145189013, 6198.535109866688, 6017.2779266488305, 6038.969805150946, 6044.546659000681, 6046.90929636047, 6061.636301821904, 5843.707814609337, 5906.911396329145, 5924.052151655715, 5934.9109763354445, 5968.313643931004, 4410.985473341086, 5840.678762668094, 5893.292804863444, 5893.998020336639, 5907.763689452374, 5930.970345148246, 5942.1978032965, 5959.1657167995645, 5961.135635332939, 5985.790497332572, 5662.518685911744, 5713.285839270091, 5822.301323841166, 5824.213731146241, 5829.38791426911], "value": 5580.597639706992, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.75052596334996, 469.0103880811489, 502.83155143027096, 518.8028549151322, 520.6797819341107, 31123.0, 31676.36647267056, 40467.19065618686, 41184.794076029575, 41424.58575414245, 38779.41830872539, 42314.0, 42328.7301380959, 42371.57628423715, 42595.787021064854, 9433.57548910299, 9625.759356016086, 9643.758906027335, 9645.276604254708, 9648.276379271547, 8549.871751923725, 8765.167309105625, 8775.25410340122, 8857.689980850671, 8859.778505537348, 11282.774344513105, 11543.0, 11580.42097895106, 11793.233439826385, 11817.70455738609, 11495.19533632646, 11575.13186511015, 11584.884151158549, 11589.710257243552, 11595.478203480863, 7131.500794944356, 8694.434861733967, 11353.829692554618, 11447.885521144764, 11455.369954652491, 31497.0, 31687.0, 31925.840370797996, 38246.42630360547, 39294.60705392938, 3424.229548351601, 3534.6995505382074, 3538.7345949053924, 3566.6789988900864, 3758.4926034985474, 29686.257843553867, 30616.693833061607, 31074.406606810764, 31162.37675246505, 31480.527792083136, 3137.905862824119, 3231.5960504936884, 3232.208109013307, 3256.2836176041233, 3376.74674399421, 405780.0, 406227.0, 481851.0, 481999.59000205126, 483013.0, 65642.0, 66241.0, 66275.67448651, 66351.33648663452, 66390.00414993778, 35623.28753424917, 36435.0, 36498.27003459916, 37785.43321850174, 68659.65670171617, 15323.61690957735, 15497.5350739478, 15512.689746205238, 15513.301901414463, 15558.455454059054, 10981.835272470918, 11037.448127593589, 11057.834132488018, 11078.723031924266, 11090.223684342018, 17169.0, 17829.821701783076, 17872.463826085237, 20507.897460512602, 20773.156965961345, 8434.57827108642, 8534.615942282611, 8544.444611100258, 8647.3946823723, 8682.262007729287, 889.8042430665373, 894.6332003878379, 919.9034101419388, 1091.476091476096, 1123.1015187849687, 25524.617130743052, 29697.257568560526, 29899.25251868677, 30603.0, 30689.38621227563, 976.9267304952157, 1003.8042581696645, 1106.5352551928208, 1109.4730003248553, 1140.2474366917795, 14969.101853888802, 15426.691466170614, 15484.535463935874, 15786.605334866721, 15853.445129420415, 646440.0, 934981.0, 940349.0, 940745.0, 940747.0, 46546.0, 56438.0, 57001.14498282529, 57017.42982570204, 57078.85842283131, 1079.1259080145107, 1280.7246442015012, 1284.9871501284867, 1372.7185926884965, 1388.2850332078988, 28235.235559095257, 29176.978805741695, 30359.20049083945, 30361.392772144874, 31616.0, 52830.67923301948, 53449.0, 53598.73200633972, 53693.0, 53828.730856345464, 590149.6895217294, 595087.0, 595505.0, 598444.0, 601666.0, 25331.60676162755, 25749.613755794053, 26598.601020985087, 26871.193864184104, 26915.59626605642, 38260.61739382682, 38519.614803851604, 38897.80551097226, 38945.0, 46859.76570117127, 35595.0, 37625.05937351588, 38437.423438647886, 39716.60283397244, 39772.801135995294, 37875.24249514939, 38083.809580951915, 38350.39795224591, 39817.0, 39853.2029359417, 38475.2304953905, 38492.807535963264, 38495.80752096222, 38557.807210963765, 40173.995650108984, 30747.770089196183, 33967.66032339645, 34658.0, 34990.0, 35021.82489087538, 22716.772832271465, 25218.873905630353, 25636.871815640803, 25783.484330313662, 25917.740822592288], "value": 19880.612061728225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.43401876622073, 377.36414142170435, 383.9212961342925, 393.0015869304586, 404.85020542399315, 19562.0, 20130.89934550327, 20450.07974641141, 21039.47401314968, 21166.682499762515, 17454.12729363533, 17752.911235443837, 19035.143418546166, 20042.29851955182, 20797.37607871766, 5153.201253805647, 5361.356637203531, 5393.352797664266, 5433.510984011438, 5454.972725136368, 3909.3745000799877, 5397.649152805055, 5491.231227628115, 5497.47773961475, 5505.421930697279, 4115.814788334517, 4146.543880173179, 5830.591858569902, 6114.205153330061, 6131.570790044699, 5774.16274640179, 6167.938320616804, 6168.96915515424, 6170.19787427636, 6175.07373893915, 6013.51891848651, 6026.758929642809, 6026.819195424145, 6045.063015232646, 6045.458408105948, 13023.609291721168, 13303.268320242383, 13494.0, 13664.521741738992, 13695.863041369657, 2152.1499007891953, 2199.604071267171, 2303.4932314890866, 2370.454795397051, 2866.3980564081567, 8486.363522735819, 9525.047495250488, 10070.446125463097, 10420.010099040579, 10575.73560660982, 1814.8094450082683, 1820.1718218210683, 1865.347128505026, 1868.3132482201784, 1868.6916658751425, 115508.42245788801, 118192.0, 130437.6956230435, 130804.6919530802, 132019.33990329987, 10027.849582256273, 11042.944785276022, 11257.21199516026, 11451.312921224753, 11558.30650160993, 14797.852021479863, 14810.62973425673, 14818.555443336718, 14825.555233343017, 14836.703265934837, 8006.639701213343, 8061.395395345373, 8189.058258300372, 8207.138250483613, 8313.459625124347, 7952.960235198787, 7960.203979602109, 8105.513669179868, 8131.268185863182, 8172.550509721904, 4453.376527286183, 4538.887972446706, 4542.205114105084, 4621.976890115595, 4645.46577143626, 1847.9630407392046, 1913.1295260656368, 1921.1738952250666, 1926.368794821771, 1931.0, 710.1549156503744, 799.160881074869, 860.3332417376583, 884.6284560484612, 899.8965119011267, 21456.0, 22056.669149962763, 22150.113995440002, 22164.002619882143, 22205.888970555367, 885.6811547842772, 925.5834874306589, 934.6495064350869, 943.8820147481565, 1027.2347101409498, 10625.362478251329, 11078.335299882034, 11530.192886498033, 11601.651950441501, 11620.825687614693, 499493.0, 548501.0, 565007.0, 571440.0, 578322.0, 127258.0, 127409.0, 129667.35166324108, 130412.3479382616, 130738.0, 913.1963871792913, 917.1699611851285, 943.0663642993387, 944.1691311645845, 948.4356807699407, 19037.90481047586, 19249.0, 19249.422517324503, 19259.711104333728, 19304.227830886608, 51567.968640627725, 52214.73892630512, 52303.476965229864, 53160.46190022042, 53428.1985770206, 62604.17447819022, 77016.84474732996, 79580.60209698914, 80973.0, 81008.59495702483, 7760.92239077602, 8091.83816323682, 8223.712170074019, 8266.504009759194, 8370.246677798783, 10675.252732308974, 12078.214916030782, 12107.213031152947, 13003.544875929298, 13169.407376668265, 10540.789184216119, 10624.575016999233, 10891.945540272565, 10997.945010274898, 11015.953484418967, 10218.642347517802, 10349.275550711378, 10506.842397363893, 10917.563297468012, 10938.617148399768, 9884.654037108667, 10272.486375681336, 10787.946060269649, 11296.943515282372, 11885.0, 7942.523448593103, 8031.397645176518, 8243.340532757245, 8449.831003379775, 8452.281556067557, 9516.33385662997, 10169.084782369622, 10618.575256989634, 11039.613613523488, 11079.279846809932], "value": 9234.15342580875, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.3082597418177, 1554.3627112883717, 1754.6490701859632, 1848.9722654160182, 1909.2935613822879, 7455.850882982337, 8854.91145088549, 8866.15771501708, 8866.911330886689, 8872.06843281455, 7740.612969351538, 7964.362850971923, 8362.03836558796, 8579.056303806576, 8620.568971551413, 2297.2074634251157, 2346.002948746784, 2348.144965477271, 2351.1065794997876, 2353.788159065684, 2301.8964146613444, 2324.8140148788143, 2336.485973085927, 2351.2828587280874, 2359.4809141988735, 2313.7570555091725, 2338.2517594369724, 2343.5781559319316, 2347.85912845229, 2352.0121548949396, 2295.0934380919443, 2312.7571604981486, 2319.3158018384534, 2319.420144963759, 2324.825638077134, 2300.7584203658707, 2302.3668491164894, 2304.8040916522114, 2307.123293148593, 2314.3866875278063, 8650.956745216234, 8718.651253949834, 8762.474251544927, 8788.956055219747, 8793.428427152214, 5167.534921857014, 5329.040772660919, 5334.706591137486, 5337.919931201035, 5338.786448542053, 3251.9674803252137, 3402.812845293508, 3648.015035940308, 3654.543182102237, 3660.3411385950517, 976.6435251133356, 1034.7154532503546, 1051.2746205118444, 1082.1342925659442, 1087.314991555323, 11226.887731122748, 11542.36516991565, 11548.307101573848, 11767.23512971654, 11796.52813887452, 4383.978080109643, 4496.437945256843, 4567.2464043432465, 4618.976905115453, 4646.0, 8994.010658827523, 9006.909930900738, 9010.963739169949, 9013.0, 9058.32062595308, 5264.236685680631, 5271.209318602164, 5272.393674727378, 5277.419483856773, 5278.9208161877605, 4166.854160104443, 4212.895643686642, 4928.926066109011, 4971.552560269593, 4977.925331120036, 3642.9817850910576, 3665.743397962117, 3739.6627108057464, 3771.4908487354405, 3787.299349620329, 1122.6800361896983, 1127.8590176227972, 1136.3920302638048, 1148.3052753084264, 1152.2970987697497, 790.4624855098588, 820.9014918209853, 901.2474583722665, 904.3669431398048, 915.2174890468686, 4087.89780255496, 5614.382417934024, 5751.827445176651, 5814.854628634318, 5984.31180414258, 407.69219239474006, 414.6620504288995, 419.19514532098447, 436.960673539383, 443.52542779226565, 3742.3263812513464, 3880.650741433228, 3970.781607011642, 3998.04047028711, 4006.238814625212, 10309.53607087683, 10595.94702026485, 10692.839607405895, 10702.25084244111, 10708.571657133627, 9696.418214906987, 9969.30214884951, 10597.364158150536, 10655.467226638792, 10846.728831779112, 8040.959795200986, 8068.314193293636, 8234.464759790475, 8321.583920803936, 8326.50040997542, 5798.420157984167, 5843.2695913010875, 5865.442782935615, 5919.260092488439, 5924.615784319486, 9739.415635061636, 9875.851862222216, 9886.851697224403, 9891.653792117242, 9991.0, 9785.951070244602, 10054.49727513636, 10069.979006298228, 10147.7463063424, 10205.979402060031, 6310.977362902685, 6782.762603308861, 7316.451266154953, 7402.814929626802, 7574.242575742602, 8364.289035432057, 8453.365997550085, 8456.534890581077, 8459.830803383775, 8471.32229421657, 7200.747973820891, 7294.088238970129, 7355.77932662021, 7430.294122058396, 7540.7197868924995, 7252.38354739853, 7375.11498620169, 7397.482176247611, 7415.147258065176, 7453.962730186315, 6842.24735279109, 6963.7562685305775, 6964.477664175106, 6966.0, 6972.546784458994, 4879.194932836042, 4932.081909565212, 4977.129002424489, 5029.1953287473825, 5189.9879523492555, 4230.492340918986, 4987.152184128634, 5068.923966140437, 5105.617078719037, 5178.456262092426], "value": 4411.157401022055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.27092436304144, 301.3490859742955, 309.1005174940922, 313.52500961044007, 316.6580093499021, 5517.668939863606, 5537.58468114891, 5549.640338117162, 5559.9444005559935, 5580.804671836487, 5088.720120393377, 5338.570950217651, 5353.652012619177, 5354.785808567653, 5486.177073438987, 4003.619656132663, 4126.484189476316, 4147.647449966741, 4171.165766846628, 4214.431051807997, 3601.873934412296, 4161.0429601191745, 4166.916661666764, 4178.164367126572, 4184.1840841035855, 3213.293075523381, 4005.5994400560003, 4133.586641335873, 4169.041120542277, 4171.833126674929, 4120.587941205886, 4165.729227600196, 4169.958300416987, 4200.369944508333, 4250.404943307939, 3037.681043490446, 3572.374834403995, 3603.9099022524383, 3612.4581312803025, 3634.128423861713, 4054.6888273684012, 4594.310853371988, 4861.197902346144, 5266.657667251617, 5324.254604355393, 3937.5471820740695, 4090.5909409058854, 4143.295639741251, 4617.976910115428, 4720.858374248778, 4817.542333478314, 5754.1944127822135, 5774.307083150007, 5784.710764461802, 5787.50806181475, 3341.1480072581335, 3390.8304584770663, 3411.4030044742317, 3555.826577229508, 3894.435306880515, 5294.338207724035, 5686.516646085088, 5733.541316694696, 5775.537956963432, 6030.577859549833, 3248.902532924016, 3875.8255878485534, 3946.9210615787524, 3947.4276229947072, 3947.8945895149404, 3448.430918965387, 3948.998760941705, 3997.6801855851463, 4019.577944315863, 4035.8991025224605, 4558.794854231567, 4637.628989680885, 4651.0, 4691.835785747482, 4753.0, 5328.333958255218, 5772.0, 5777.104548794943, 5783.942160578425, 5790.481140703723, 1044.9634262800917, 1278.7826069568196, 1377.7944298738603, 1378.1593228130832, 1382.1776043254247, 772.6600295869799, 784.0395515493589, 804.8108694456798, 809.4455298120806, 836.8869403693074, 4515.0, 4532.614727748179, 4538.909221815546, 4545.727076504912, 4651.790669419885, 4230.9365359519015, 4327.836081959021, 4730.273450190655, 4792.664513484093, 4929.077659712715, 3626.492291079251, 3868.7872167031082, 4451.241759505042, 4516.774161291923, 4541.68208225421, 2317.154238702861, 2589.7669209770834, 2612.8301660392012, 2628.6188502667005, 2636.327736427199, 4304.169289332496, 4710.905781884411, 4712.256465107932, 4971.204607262821, 5096.769681164625, 3643.825767410284, 4687.000319795334, 4788.592969597623, 4838.137317132859, 4891.584215341664, 4202.768847713344, 4353.258696521417, 4761.452432970253, 5072.746362681777, 5136.486351364908, 3882.3987928087686, 3943.093088589583, 4259.850905218303, 4329.891752706208, 4912.582430493305, 4277.080427708058, 4350.804213810323, 4496.977515112403, 4624.745638989888, 4848.9757551212015, 5432.456754324536, 5726.770929162787, 6217.595856269328, 6280.026595877646, 6287.559870808999, 3518.401871681769, 3937.5274967004143, 3949.3681011038097, 3992.963588569792, 4010.6791456682813, 4587.6788624795945, 5035.521625445724, 5635.168570214779, 6115.0, 6130.601510901777, 5152.922706159486, 5595.692236927008, 5707.886962042362, 5851.882962340815, 5873.882522349443, 4831.801530303633, 5806.970965145148, 5808.680522571299, 5810.767569297181, 5848.356680765198, 5345.518903298722, 5709.885802284014, 5721.141828725725, 5750.25246717924, 5751.683657398883, 4522.525134860793, 5546.533416614616, 5721.771129154788, 5737.454941780524, 5786.247787787561, 4937.407511098691, 4945.357103576512, 5641.492265696107, 5662.716864156858, 5662.943370566241], "value": 3929.1287654838056, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.0176723475366, 718.6730037832785, 722.4870342057139, 755.6335177438941, 839.0979696825912, 14290.356933937972, 14989.025713328627, 14994.375984202748, 14998.700025999473, 15263.313150908209, 13610.31948402581, 13697.794533082011, 13701.862981370208, 13720.45118195271, 13721.588352349421, 676.1345477788415, 709.8118998465411, 710.5168485429906, 712.8146681862701, 873.7029410000598, 708.9113860767404, 713.3793599568382, 841.2302742990157, 883.3021912688968, 888.2361169394316, 929.8930622978343, 935.8034812689343, 946.8343039968013, 947.90520947905, 948.3077353531926, 934.2339281788902, 935.2985261054175, 937.6483818568037, 937.6530683647026, 937.6624415210518, 531.4207513809965, 557.5066066531109, 700.737223541172, 701.7087908517959, 703.0614130136274, 13625.727485450236, 14090.0, 14163.929180354033, 14407.207603581799, 14423.350949207312, 11746.941265293704, 13743.03798734089, 14141.929290353482, 14251.216183109926, 14275.643108922257, 11506.0, 11605.303681779036, 11634.243774154653, 11663.94168029163, 11758.470868810924, 3699.264424296382, 3725.2921944830396, 3773.64150405711, 3785.6403641653997, 3798.335291324035, 87666.5616671919, 87727.0, 88389.11610883937, 88874.55562722145, 89625.0, 10690.0, 10821.026107650348, 10896.0, 10992.945035274772, 11161.83257251142, 18044.54888627795, 21770.564588708454, 21785.673214901788, 21860.45348866291, 21875.234366797085, 7668.1181664109345, 7719.65261563231, 7758.9612051939375, 7797.376209903194, 7918.683252669944, 4281.785910704452, 4338.566143385699, 4376.212281789309, 4458.175237581057, 4539.273716205457, 3241.3517296540786, 3380.7295416366633, 3442.5868895732674, 3544.3442963051916, 3551.6448355164794, 594.6996766632823, 606.8088552106161, 620.7827260458803, 624.9406306400884, 657.2080642825384, 627.8869803435425, 644.5101722690789, 656.5666660004374, 664.7806223946183, 676.9686516792489, 8293.917060829435, 8912.509811960404, 9011.27909767217, 9053.637854485747, 9064.864027039599, 499.9925001124986, 598.637824116412, 643.6299128001418, 652.9422335815914, 659.6009414304373, 4301.698881078357, 4429.512753597101, 4606.539346065367, 4779.832705855279, 4820.3492528508905, 109994.95078262882, 116258.41870790592, 118601.40699296448, 118886.40556797334, 123739.0, 95234.04765952252, 95266.0, 95619.52190239144, 96636.0, 98592.59148155346, 79704.60147699225, 86490.56754716186, 87494.56252718695, 89076.0, 89645.72616887401, 9262.907370926205, 9637.544103042595, 9687.012747642542, 9699.463736351086, 9718.558045536442, 54797.726011371284, 56138.0, 56285.8742825133, 56301.71849140728, 56801.0, 75694.48611027638, 80098.59950700209, 81676.18323816924, 83315.333693327, 85022.0, 12493.063020273334, 12600.226816020358, 12734.981201504042, 12842.972562194815, 12999.935000324938, 12224.877751222373, 13719.45122195101, 14140.0, 14379.0, 14412.063215890934, 11363.37501437429, 13101.0, 13261.668458288234, 13580.388882500114, 13661.38523766413, 13055.804162937753, 13262.67373262666, 13517.0, 13681.863181368059, 13987.580372588838, 10902.672919812418, 11173.88826111761, 13213.339333033502, 13249.668758281121, 13663.590092297247, 10805.580915333612, 11042.227044106836, 11185.888141118485, 11293.322400655987, 11554.826677600011, 10397.220208484243, 10418.322809017389, 11019.393933333744, 11235.157363197957, 11439.370834604177], "value": 7413.754776982116, "unit": "score"}], "name": "dromaeo_css", "value": 7132.4803237872875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 100.0, 129.0, 185.0, 143.0, 112.0, 112.0, 115.0, 141.0, 111.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 183.0, 182.0, 183.0, 183.0, 187.0, 184.0, 181.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 61.0, 60.0, 62.0, 57.0, 82.0, 58.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 91.0, 69.0, 67.0, 68.0, 91.0, 68.0, 68.0, 91.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 83.0, 87.0, 86.0, 88.0, 85.0, 87.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 96.0, 97.0, 95.0, 117.0, 161.0, 100.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 81.0, 78.0, 82.0, 81.0, 85.0, 85.0, 83.0, 83.0], "value": 82.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 53.0, 54.0, 56.0, 56.0, 55.0, 54.0, 55.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 76.0, 75.0, 71.0, 69.0, 69.0, 71.0, 69.0, 69.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 78.0, 88.0, 98.0, 106.0, 109.0, 106.0, 90.0, 87.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 147.0, 152.0, 156.0, 145.0, 149.0, 147.0, 179.0, 156.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 174.0, 171.0, 173.0, 171.0, 172.0, 230.0, 172.0, 172.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 90.0, 87.0, 69.0, 69.0, 68.0, 68.0, 69.0, 72.0, 78.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1468.1000000000001, "alertThreshold": 2.0}]} 05:58:35 INFO - Return code: 0 05:58:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:35 INFO - # TBPL SUCCESS # 05:58:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:35 INFO - 2016-10-26 05:58:35.351 screenresolution[1682:11904] starting screenresolution argv=screenresolution get 05:58:35 INFO - 2016-10-26 05:58:35.354 screenresolution[1682:11904] Display 0: 1600x1200x32@60 05:58:35 INFO - 2016-10-26 05:58:35.360 screenresolution[1683:11907] starting screenresolution argv=screenresolution list 05:58:35 INFO - Available Modes on Display 0 05:58:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:35 INFO - Intel Iris: 05:58:35 INFO - Chipset Model: Intel Iris 05:58:35 INFO - Type: GPU 05:58:35 INFO - Bus: Built-In 05:58:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:35 INFO - Vendor: Intel (0x8086) 05:58:35 INFO - Device ID: 0x0a2e 05:58:35 INFO - Revision ID: 0x0009 05:58:35 INFO - Displays: 05:58:35 INFO - Display: 05:58:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:35 INFO - Display Serial Number: 1600x1200 60 05:58:35 INFO - Main Display: Yes 05:58:35 INFO - Mirror: Off 05:58:35 INFO - Online: Yes 05:58:35 INFO - Rotation: Supported 05:58:35 INFO - Return code: 0 05:58:35 INFO - Running post-action listener: _resource_record_post_action 05:58:35 INFO - [mozharness: 2016-10-26 12:58:35.597725Z] Finished run-tests step (success) 05:58:35 INFO - Running post-run listener: _resource_record_post_run 05:58:35 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 31398912; Write bytes: 707344384; Read time: 304; Write time: 1938 05:58:35 INFO - TinderboxPrint: CPU usage
53.5% 05:58:35 INFO - TinderboxPrint: I/O read bytes / time
31,398,912 / 304 05:58:35 INFO - TinderboxPrint: I/O write bytes / time
707,344,384 / 1,938 05:58:35 INFO - TinderboxPrint: CPU idle
2,258.6 (46.5%) 05:58:35 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 05:58:35 INFO - TinderboxPrint: CPU user
1,629.0 (33.5%) 05:58:35 INFO - TinderboxPrint: Swap in / out
431,353,856 / 0 05:58:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401521152; Write bytes: 396175360; Read time: 29775; Write time: 755 05:58:35 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29309952; Write bytes: 297836544; Read time: 282; Write time: 1148 05:58:36 INFO - Running post-run listener: _upload_blobber_files 05:58:36 INFO - Blob upload gear active. 05:58:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:36 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 05:58:36 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'] 05:58:36 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 05:58:36 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:38 INFO - (blobuploader) - INFO - Done attempting. 05:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:38 INFO - (blobuploader) - INFO - Done attempting. 05:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:42 INFO - (blobuploader) - INFO - Done attempting. 05:58:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:42 INFO - Return code: 0 05:58:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ea352475cb325dd39dd099b658c5a2dbdaf76041924040a1aa7d8e43d4c917d68b94e2729a9aa98b0fad41249527887059bb0268b32c9d96dd9b36a0d5d1125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7994772ad23d3ef37c4ac82d6e72323e8f2b5fd6350e36de38322db4d5aa3eac5587d6c79e2591752a0a09a7cec3261fef65584d685ae0881a01d9d1deb14b73"} 05:58:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:42 INFO - Contents: 05:58:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ea352475cb325dd39dd099b658c5a2dbdaf76041924040a1aa7d8e43d4c917d68b94e2729a9aa98b0fad41249527887059bb0268b32c9d96dd9b36a0d5d1125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7994772ad23d3ef37c4ac82d6e72323e8f2b5fd6350e36de38322db4d5aa3eac5587d6c79e2591752a0a09a7cec3261fef65584d685ae0881a01d9d1deb14b73"} 05:58:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:42 INFO - Copying logs to upload dir... 05:58:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.757754 ========= master_lag: 1.47 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-26 05:58:44.088938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 05:58:44.092421) ========= 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.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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/5ea352475cb325dd39dd099b658c5a2dbdaf76041924040a1aa7d8e43d4c917d68b94e2729a9aa98b0fad41249527887059bb0268b32c9d96dd9b36a0d5d1125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7994772ad23d3ef37c4ac82d6e72323e8f2b5fd6350e36de38322db4d5aa3eac5587d6c79e2591752a0a09a7cec3261fef65584d685ae0881a01d9d1deb14b73"} build_url:https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011059 build_url: 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/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/5ea352475cb325dd39dd099b658c5a2dbdaf76041924040a1aa7d8e43d4c917d68b94e2729a9aa98b0fad41249527887059bb0268b32c9d96dd9b36a0d5d1125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7994772ad23d3ef37c4ac82d6e72323e8f2b5fd6350e36de38322db4d5aa3eac5587d6c79e2591752a0a09a7cec3261fef65584d685ae0881a01d9d1deb14b73"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 05:58:44.160780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-26 05:58:44.161088) ========= 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.evlNAuiIG0/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.e1iaKRAMFp/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005094 ========= master_lag: 4.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-26 05:58:48.179476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 05:58:48.179870) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 05:58:48.182991) ========= ========= Total master_lag: 5.61 =========