builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0206 starttime: 1479223721.61 results: success (0) buildid: 20161115050514 builduid: 7547e53e3d4e473b80943fef03480bc2 revision: fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:41.613429) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:41.616153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:41.616642) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:41.654065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:41.654418) ========= 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.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-15 07:28:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.55M=0.002s 2016-11-15 07:28:41 (7.55 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.338816 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:42.015054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:42.015367) ========= 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.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.020884 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:42.067582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 07:28:42.069978) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-15 07:28:42,152 truncating revision to first 12 chars 2016-11-15 07:28:42,152 Setting DEBUG logging. 2016-11-15 07:28:42,152 attempt 1/10 2016-11-15 07:28:42,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fb3473c1770d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 07:28:42,987 unpacking tar archive at: mozilla-central-fb3473c1770d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.098470 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 07:28:43.190461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:43.190797) ========= script_repo_revision: fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:43.191200) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:43.191468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 07:28:43.209590) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 07:28:43.209958) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:28:43 INFO - MultiFileLogger online at 20161115 07:28:43 in /builds/slave/test 07:28:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:28:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:28:43 INFO - {'append_to_log': False, 07:28:43 INFO - 'base_work_dir': '/builds/slave/test', 07:28:43 INFO - 'blob_upload_branch': 'Firefox', 07:28:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:28:43 INFO - 'branch': 'Firefox', 07:28:43 INFO - 'buildbot_json_path': 'buildprops.json', 07:28:43 INFO - 'config_files': ('talos/mac_config.py',), 07:28:43 INFO - 'default_actions': ('clobber', 07:28:43 INFO - 'read-buildbot-config', 07:28:43 INFO - 'download-and-extract', 07:28:43 INFO - 'populate-webroot', 07:28:43 INFO - 'create-virtualenv', 07:28:43 INFO - 'install', 07:28:43 INFO - 'run-tests'), 07:28:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:28:43 INFO - 'download_minidump_stackwalk': True, 07:28:43 INFO - 'download_symbols': 'ondemand', 07:28:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:28:43 INFO - 'tooltool.py': '/tools/tooltool.py', 07:28:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:28:43 INFO - '/tools/misc-python/virtualenv.py')}, 07:28:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:28:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:28:43 INFO - 'installer_path': 'installer.exe', 07:28:43 INFO - 'log_level': 'info', 07:28:43 INFO - 'log_name': 'talos', 07:28:43 INFO - 'log_to_console': True, 07:28:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:28:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:28:43 INFO - 'opt_config_files': (), 07:28:43 INFO - 'pip_index': False, 07:28:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:28:43 INFO - 'cmd': ('bash', 07:28:43 INFO - '-c', 07:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:28:43 INFO - 'enabled': True, 07:28:43 INFO - 'halt_on_failure': False, 07:28:43 INFO - 'name': 'check_screen_resolution'},), 07:28:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:28:43 INFO - 'cmd': ('bash', 07:28:43 INFO - '-c', 07:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:28:43 INFO - 'enabled': True, 07:28:43 INFO - 'halt_on_failure': False, 07:28:43 INFO - 'name': 'check_screen_resolution'},), 07:28:43 INFO - 'run_cmd_checks_enabled': True, 07:28:43 INFO - 'sps_profile': False, 07:28:43 INFO - 'sps_profile_interval': 0, 07:28:43 INFO - 'suite': 'dromaeojs', 07:28:43 INFO - 'system_bits': '32', 07:28:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:28:43 INFO - 'title': 't-yosemite-r7-0206', 07:28:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:28:43 INFO - 'use_talos_json': True, 07:28:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:28:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:28:43 INFO - 'work_dir': 'build'} 07:28:43 INFO - [mozharness: 2016-11-15 15:28:43.415041Z] Running clobber step. 07:28:43 INFO - Running pre-action listener: _resource_record_pre_action 07:28:43 INFO - Running main action method: clobber 07:28:43 INFO - rmtree: /builds/slave/test/build 07:28:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:28:45 INFO - Running post-action listener: _resource_record_post_action 07:28:45 INFO - [mozharness: 2016-11-15 15:28:45.096221Z] Finished clobber step (success) 07:28:45 INFO - [mozharness: 2016-11-15 15:28:45.096493Z] Running read-buildbot-config step. 07:28:45 INFO - Running pre-action listener: _resource_record_pre_action 07:28:45 INFO - Running main action method: read_buildbot_config 07:28:45 INFO - Using buildbot properties: 07:28:45 INFO - { 07:28:45 INFO - "project": "", 07:28:45 INFO - "product": "firefox", 07:28:45 INFO - "script_repo_revision": "production", 07:28:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:28:45 INFO - "repository": "", 07:28:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:28:45 INFO - "buildid": "20161115050514", 07:28:45 INFO - "pgo_build": "False", 07:28:45 INFO - "basedir": "/builds/slave/test", 07:28:45 INFO - "buildnumber": 17, 07:28:45 INFO - "slavename": "t-yosemite-r7-0206", 07:28:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:28:45 INFO - "platform": "yosemite_r7", 07:28:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:28:45 INFO - "branch": "mozilla-central", 07:28:45 INFO - "repo_path": "mozilla-central", 07:28:45 INFO - "revision": "fb3473c1770d3dace1ec09dfbb574fabe7cb26b1", 07:28:45 INFO - "stage_platform": "macosx64", 07:28:45 INFO - "builduid": "7547e53e3d4e473b80943fef03480bc2", 07:28:45 INFO - "slavebuilddir": "test" 07:28:45 INFO - } 07:28:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:28:45 INFO - Running post-action listener: _resource_record_post_action 07:28:45 INFO - [mozharness: 2016-11-15 15:28:45.100872Z] Finished read-buildbot-config step (success) 07:28:45 INFO - [mozharness: 2016-11-15 15:28:45.100997Z] Running download-and-extract step. 07:28:45 INFO - Running pre-action listener: _resource_record_pre_action 07:28:45 INFO - Running main action method: download_and_extract 07:28:45 INFO - mkdir: /builds/slave/test/build/tests 07:28: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')]} 07:28:45 INFO - trying https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:28:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:28:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:28:46 INFO - Downloaded 1199 bytes. 07:28:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:28:46 INFO - Using the following test package requirements: 07:28:46 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:28:46 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:28:46 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:28:46 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'jsshell-mac.zip'], 07:28:46 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:28:46 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:28:46 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:28:46 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:28:46 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:28:46 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:28:46 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:28:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:28:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:28:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:28:46 INFO - Fetch https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:28:46 INFO - Http code: 200 07:28:46 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 07:28:46 INFO - x-amz-cf-id: pjWQehPJAWFa5rwqQFZN8hMxVA2QroDZm7qzHK84NoUIfdr0YDiG1A== 07:28:46 INFO - x-amz-version-id: 24pImCI74WbGfo6SxBM4AQmzVOxagqQR 07:28:46 INFO - Expected file size: 16330766 07:28:46 INFO - Obtained file size: 16330766 07:28:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:28:53 INFO - Http code: 200 07:28:53 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 07:28:53 INFO - x-amz-cf-id: d5fITRTmahSVnEBR-o-490PMwbZ6shs62QrTjGfXEnSOLAwGnITGew== 07:28:53 INFO - x-amz-version-id: 24pImCI74WbGfo6SxBM4AQmzVOxagqQR 07:28:54 INFO - Expected file size: 16330766 07:28:54 INFO - Obtained file size: 16330766 07:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:29:01 INFO - Http code: 200 07:29:01 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 07:29:01 INFO - x-amz-cf-id: 21IUogSLJIxfFfiRFZJfoWN9IVG_c-2ksinWsem6s_RknpJjGXbnQw== 07:29:01 INFO - x-amz-version-id: 8gHZyJ1dr0AHNgn8ed2bc4pyYKxOTtMI 07:29:01 INFO - Expected file size: 14409038 07:29:01 INFO - Obtained file size: 14409038 07:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:02 INFO - trying https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:29:04 INFO - Downloaded 110974546 bytes. 07:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:29:04 INFO - mkdir: /builds/slave/test/properties 07:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:29:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:29:04 INFO - Contents: 07:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:29:05 INFO - Running post-action listener: _resource_record_post_action 07:29:05 INFO - Running post-action listener: set_extra_try_arguments 07:29:05 INFO - [mozharness: 2016-11-15 15:29:05.699214Z] Finished download-and-extract step (success) 07:29:05 INFO - [mozharness: 2016-11-15 15:29:05.699361Z] Running populate-webroot step. 07:29:05 INFO - Running pre-action listener: _resource_record_pre_action 07:29:05 INFO - Running main action method: populate_webroot 07:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:29:05 INFO - '--tppagecycles', 07:29:05 INFO - '7'], 07:29:05 INFO - 'tests': ['tsvgm']}}, 07:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:29:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['damp', 'tps']}, 07:29:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'tests': ['damp', 'tps']}, 07:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['dromaeo_dom']}, 07:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:29:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['basic_compositor_video']}, 07:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['a11yr', 07:29:05 INFO - 'ts_paint', 07:29:05 INFO - 'tpaint', 07:29:05 INFO - 'sessionrestore', 07:29:05 INFO - 'sessionrestore_no_auto_restore', 07:29:05 INFO - 'tabpaint']}, 07:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['tsvgx', 07:29:05 INFO - 'tsvgr_opacity', 07:29:05 INFO - 'tart', 07:29:05 INFO - 'tscrollx', 07:29:05 INFO - 'cart']}, 07:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:29:05 INFO - 'tsvgr_opacity', 07:29:05 INFO - 'tart', 07:29:05 INFO - 'tscrollx', 07:29:05 INFO - 'cart']}, 07:29:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'talos_options': ['--disable-e10s'], 07:29:05 INFO - 'tests': ['tp5o']}, 07:29:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'tests': ['tp5o']}, 07:29:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'talos_options': ['--disable-e10s', 07:29:05 INFO - '--xperf_path', 07:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:05 INFO - 'tests': ['tp5n']}, 07:29:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:05 INFO - 'talos_options': ['--xperf_path', 07:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:05 INFO - 'tests': ['tp5n']}}, 07:29:05 INFO - 'talos.zip': {'path': '', 07:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:29:05 INFO - Running post-action listener: _resource_record_post_action 07:29:05 INFO - [mozharness: 2016-11-15 15:29:05.717443Z] Finished populate-webroot step (success) 07:29:05 INFO - [mozharness: 2016-11-15 15:29:05.717537Z] Running create-virtualenv step. 07:29:05 INFO - Running pre-action listener: _resource_record_pre_action 07:29:05 INFO - Running main action method: create_virtualenv 07:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:29:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:29:05 INFO - Using real prefix '/tools/python27' 07:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:29:09 INFO - Installing pip.................done. 07:29:09 INFO - Return code: 0 07:29:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:29:09 INFO - Reading from file tmpfile_stdout 07:29:09 INFO - Output received: 07:29:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:09 INFO - 'HOME': '/Users/cltbld', 07:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:09 INFO - 'LOGNAME': 'cltbld', 07:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:09 INFO - 'MOZ_NO_REMOTE': '1', 07:29:09 INFO - 'NO_EM_RESTART': '1', 07:29:09 INFO - 'PAGER': '/bin/cat', 07:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:09 INFO - 'PWD': '/builds/slave/test', 07:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:09 INFO - 'SHELL': '/bin/bash', 07:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:09 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:09 INFO - 'USER': 'cltbld', 07:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:09 INFO - 'XPC_FLAGS': '0x0', 07:29:09 INFO - 'XPC_SERVICE_NAME': '0', 07:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:29:09 INFO - Cleaning up... 07:29:09 INFO - Return code: 0 07:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:09 INFO - 'HOME': '/Users/cltbld', 07:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:09 INFO - 'LOGNAME': 'cltbld', 07:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:09 INFO - 'MOZ_NO_REMOTE': '1', 07:29:09 INFO - 'NO_EM_RESTART': '1', 07:29:09 INFO - 'PAGER': '/bin/cat', 07:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:09 INFO - 'PWD': '/builds/slave/test', 07:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:09 INFO - 'SHELL': '/bin/bash', 07:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:09 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:09 INFO - 'USER': 'cltbld', 07:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:09 INFO - 'XPC_FLAGS': '0x0', 07:29:09 INFO - 'XPC_SERVICE_NAME': '0', 07:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:10 INFO - Downloading/unpacking psutil>=3.1.1 07:29:10 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 07:29:10 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 07:29:10 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 07:29:10 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 07:29:10 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 07:29:10 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 07:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:13 INFO - Installing collected packages: psutil 07:29:13 INFO - Running setup.py install for psutil 07:29:13 INFO - building 'psutil._psutil_osx' extension 07:29:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:29:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:29:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:29:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:29:14 INFO - building 'psutil._psutil_posix' extension 07:29:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:29:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:29:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:29:14 INFO - ^ 07:29:14 INFO - 1 warning generated. 07:29:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:14 INFO - Successfully installed psutil 07:29:14 INFO - Cleaning up... 07:29:14 INFO - Return code: 0 07:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:29: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')]} 07:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29: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')]} 07:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:14 INFO - 'HOME': '/Users/cltbld', 07:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:14 INFO - 'LOGNAME': 'cltbld', 07:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:14 INFO - 'MOZ_NO_REMOTE': '1', 07:29:14 INFO - 'NO_EM_RESTART': '1', 07:29:14 INFO - 'PAGER': '/bin/cat', 07:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:14 INFO - 'PWD': '/builds/slave/test', 07:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:14 INFO - 'SHELL': '/bin/bash', 07:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:14 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:14 INFO - 'USER': 'cltbld', 07:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:14 INFO - 'XPC_FLAGS': '0x0', 07:29:14 INFO - 'XPC_SERVICE_NAME': '0', 07:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:29:17 INFO - Installing collected packages: mozsystemmonitor 07:29:17 INFO - Running setup.py install for mozsystemmonitor 07:29:17 INFO - Successfully installed mozsystemmonitor 07:29:17 INFO - Cleaning up... 07:29:17 INFO - Return code: 0 07:29:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:17 INFO - 'HOME': '/Users/cltbld', 07:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:17 INFO - 'LOGNAME': 'cltbld', 07:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:17 INFO - 'MOZ_NO_REMOTE': '1', 07:29:17 INFO - 'NO_EM_RESTART': '1', 07:29:17 INFO - 'PAGER': '/bin/cat', 07:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:17 INFO - 'PWD': '/builds/slave/test', 07:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:17 INFO - 'SHELL': '/bin/bash', 07:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:17 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:17 INFO - 'USER': 'cltbld', 07:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:17 INFO - 'XPC_FLAGS': '0x0', 07:29:17 INFO - 'XPC_SERVICE_NAME': '0', 07:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:17 INFO - Downloading/unpacking jsonschema==2.5.1 07:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:29:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:20 INFO - Downloading functools32-3.2.3-2.tar.gz 07:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:29:20 INFO - warning: no files found matching '*.txt' 07:29:20 INFO - no previously-included directories found matching 'build' 07:29:20 INFO - no previously-included directories found matching 'dist' 07:29:20 INFO - no previously-included directories found matching '.git*' 07:29:20 INFO - Installing collected packages: jsonschema, functools32 07:29:20 INFO - Running setup.py install for functools32 07:29:20 INFO - warning: no files found matching '*.txt' 07:29:20 INFO - no previously-included directories found matching 'build' 07:29:20 INFO - no previously-included directories found matching 'dist' 07:29:20 INFO - no previously-included directories found matching '.git*' 07:29:20 INFO - Successfully installed jsonschema functools32 07:29:20 INFO - Cleaning up... 07:29:21 INFO - Return code: 0 07:29:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:21 INFO - 'HOME': '/Users/cltbld', 07:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:21 INFO - 'LOGNAME': 'cltbld', 07:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:21 INFO - 'MOZ_NO_REMOTE': '1', 07:29:21 INFO - 'NO_EM_RESTART': '1', 07:29:21 INFO - 'PAGER': '/bin/cat', 07:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:21 INFO - 'PWD': '/builds/slave/test', 07:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:21 INFO - 'SHELL': '/bin/bash', 07:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:21 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:21 INFO - 'USER': 'cltbld', 07:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:21 INFO - 'XPC_FLAGS': '0x0', 07:29:21 INFO - 'XPC_SERVICE_NAME': '0', 07:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:29:21 INFO - Cleaning up... 07:29:21 INFO - Return code: 0 07:29:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:21 INFO - 'HOME': '/Users/cltbld', 07:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:21 INFO - 'LOGNAME': 'cltbld', 07:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:21 INFO - 'MOZ_NO_REMOTE': '1', 07:29:21 INFO - 'NO_EM_RESTART': '1', 07:29:21 INFO - 'PAGER': '/bin/cat', 07:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:21 INFO - 'PWD': '/builds/slave/test', 07:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:21 INFO - 'SHELL': '/bin/bash', 07:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:21 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:21 INFO - 'USER': 'cltbld', 07:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:21 INFO - 'XPC_FLAGS': '0x0', 07:29:21 INFO - 'XPC_SERVICE_NAME': '0', 07:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:21 INFO - Downloading/unpacking blobuploader==1.2.4 07:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:24 INFO - Downloading blobuploader-1.2.4.tar.gz 07:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:29:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:29:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:25 INFO - Downloading docopt-0.6.1.tar.gz 07:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:29:25 INFO - Installing collected packages: blobuploader, requests, docopt 07:29:25 INFO - Running setup.py install for blobuploader 07:29:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:29:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:29:25 INFO - Running setup.py install for requests 07:29:26 INFO - Running setup.py install for docopt 07:29:26 INFO - Successfully installed blobuploader requests docopt 07:29:26 INFO - Cleaning up... 07:29:26 INFO - Return code: 0 07:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:26 INFO - 'HOME': '/Users/cltbld', 07:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:26 INFO - 'LOGNAME': 'cltbld', 07:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:26 INFO - 'MOZ_NO_REMOTE': '1', 07:29:26 INFO - 'NO_EM_RESTART': '1', 07:29:26 INFO - 'PAGER': '/bin/cat', 07:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:26 INFO - 'PWD': '/builds/slave/test', 07:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:26 INFO - 'SHELL': '/bin/bash', 07:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:26 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:26 INFO - 'USER': 'cltbld', 07:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:26 INFO - 'XPC_FLAGS': '0x0', 07:29:26 INFO - 'XPC_SERVICE_NAME': '0', 07:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:29:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-likZ7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:29:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8GdHdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:29:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-3_IeNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:29:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-f9ljVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:29:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8BehIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-bDv2DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-MWNrhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-b1amSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-jro6cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-jb1FTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-nzRxlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-f5qHaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-mVJv7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:29:27 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-xwGlvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:28 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-EMTUA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:29:28 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-vcxDaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:29:28 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-KSOlfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:29:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:29:28 INFO - Running setup.py install for manifestparser 07:29:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:29:28 INFO - Running setup.py install for mozcrash 07:29:28 INFO - Running setup.py install for mozdebug 07:29:28 INFO - Running setup.py install for mozdevice 07:29:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:29:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:29:28 INFO - Running setup.py install for mozfile 07:29:29 INFO - Running setup.py install for mozhttpd 07:29:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Running setup.py install for mozinfo 07:29:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Running setup.py install for mozInstall 07:29:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Running setup.py install for mozleak 07:29:29 INFO - Running setup.py install for mozlog 07:29:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Running setup.py install for moznetwork 07:29:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:29:29 INFO - Running setup.py install for mozprocess 07:29:30 INFO - Running setup.py install for mozprofile 07:29:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:29:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:29:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:29:30 INFO - Running setup.py install for mozrunner 07:29:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:29:30 INFO - Running setup.py install for mozscreenshot 07:29:30 INFO - Running setup.py install for moztest 07:29:30 INFO - Running setup.py install for mozversion 07:29:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:29:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:29:30 INFO - Cleaning up... 07:29:30 INFO - Return code: 0 07:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:30 INFO - 'HOME': '/Users/cltbld', 07:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:30 INFO - 'LOGNAME': 'cltbld', 07:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:30 INFO - 'MOZ_NO_REMOTE': '1', 07:29:30 INFO - 'NO_EM_RESTART': '1', 07:29:30 INFO - 'PAGER': '/bin/cat', 07:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:30 INFO - 'PWD': '/builds/slave/test', 07:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:30 INFO - 'SHELL': '/bin/bash', 07:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:30 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:30 INFO - 'USER': 'cltbld', 07:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:30 INFO - 'XPC_FLAGS': '0x0', 07:29:30 INFO - 'XPC_SERVICE_NAME': '0', 07:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ZPSMcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ACdqPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Em51JK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Cvqee3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ekTmMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ulHgw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-z4DkK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:29:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8wOU8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-UfxfXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-vf1nqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fMP1xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-FVggoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-qxtE08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-cNDdxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-U3isoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-36dO2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:29:32 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-NDpN7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:36 INFO - Downloading blessings-1.6.tar.gz 07:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:29:36 INFO - Installing collected packages: blessings 07:29:36 INFO - Running setup.py install for blessings 07:29:36 INFO - Successfully installed blessings 07:29:36 INFO - Cleaning up... 07:29:36 INFO - Return code: 0 07:29:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:29:36 INFO - Reading from file tmpfile_stdout 07:29:36 INFO - Current package versions: 07:29:36 INFO - blessings == 1.6 07:29:36 INFO - blobuploader == 1.2.4 07:29:36 INFO - docopt == 0.6.1 07:29:36 INFO - functools32 == 3.2.3-2 07:29:36 INFO - jsonschema == 2.5.1 07:29:36 INFO - manifestparser == 1.1 07:29:36 INFO - mozInstall == 1.12 07:29:36 INFO - mozcrash == 1.0 07:29:36 INFO - mozdebug == 0.1 07:29:36 INFO - mozdevice == 0.48 07:29:36 INFO - mozfile == 1.2 07:29:36 INFO - mozhttpd == 0.7 07:29:36 INFO - mozinfo == 0.9 07:29:36 INFO - mozleak == 0.1 07:29:36 INFO - mozlog == 3.4 07:29:36 INFO - moznetwork == 0.27 07:29:36 INFO - mozprocess == 0.23 07:29:36 INFO - mozprofile == 0.28 07:29:36 INFO - mozrunner == 6.13 07:29:36 INFO - mozscreenshot == 0.1 07:29:36 INFO - mozsystemmonitor == 0.3 07:29:36 INFO - moztest == 0.7 07:29:36 INFO - mozversion == 1.4 07:29:36 INFO - psutil == 3.1.1 07:29:36 INFO - requests == 1.2.3 07:29:36 INFO - wsgiref == 0.1.2 07:29:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:37 INFO - 'HOME': '/Users/cltbld', 07:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:37 INFO - 'LOGNAME': 'cltbld', 07:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:37 INFO - 'MOZ_NO_REMOTE': '1', 07:29:37 INFO - 'NO_EM_RESTART': '1', 07:29:37 INFO - 'PAGER': '/bin/cat', 07:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:37 INFO - 'PWD': '/builds/slave/test', 07:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:37 INFO - 'SHELL': '/bin/bash', 07:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:37 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:37 INFO - 'USER': 'cltbld', 07:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:37 INFO - 'XPC_FLAGS': '0x0', 07:29:37 INFO - 'XPC_SERVICE_NAME': '0', 07:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:29:37 INFO - Cleaning up... 07:29:37 INFO - Return code: 0 07:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10425e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f845858ebb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, '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.zOOQPxFmri/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.ArIM9InbaA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:37 INFO - 'HOME': '/Users/cltbld', 07:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:37 INFO - 'LOGNAME': 'cltbld', 07:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:37 INFO - 'MOZ_NO_REMOTE': '1', 07:29:37 INFO - 'NO_EM_RESTART': '1', 07:29:37 INFO - 'PAGER': '/bin/cat', 07:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:37 INFO - 'PWD': '/builds/slave/test', 07:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:37 INFO - 'SHELL': '/bin/bash', 07:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:29:37 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:37 INFO - 'USER': 'cltbld', 07:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:37 INFO - 'XPC_FLAGS': '0x0', 07:29:37 INFO - 'XPC_SERVICE_NAME': '0', 07:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:29:37 INFO - Cleaning up... 07:29:37 INFO - Return code: 0 07:29:37 INFO - Running post-action listener: _resource_record_post_action 07:29:37 INFO - Running post-action listener: _start_resource_monitoring 07:29:37 INFO - Starting resource monitoring. 07:29:37 INFO - [mozharness: 2016-11-15 15:29:37.658397Z] Finished create-virtualenv step (success) 07:29:37 INFO - [mozharness: 2016-11-15 15:29:37.659491Z] Running install step. 07:29:37 INFO - Running pre-action listener: _resource_record_pre_action 07:29:37 INFO - Running main action method: install 07:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:29:37 INFO - Reading from file tmpfile_stdout 07:29:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:29:38 INFO - Reading from file tmpfile_stdout 07:29:38 INFO - Output received: 07:29:38 INFO - Usage: mozinstall [options] installer 07:29:38 INFO - Options: 07:29:38 INFO - -h, --help show this help message and exit 07:29:38 INFO - -d DEST, --destination=DEST 07:29:38 INFO - Directory to install application into. [default: 07:29:38 INFO - "/builds/slave/test"] 07:29:38 INFO - --app=APP Application being installed. [default: firefox] 07:29:38 INFO - mkdir: /builds/slave/test/build/application 07:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:30:12 INFO - Reading from file tmpfile_stdout 07:30:12 INFO - Output received: 07:30:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:30:12 INFO - Running post-action listener: _resource_record_post_action 07:30:12 INFO - [mozharness: 2016-11-15 15:30:12.147136Z] Finished install step (success) 07:30:12 INFO - [mozharness: 2016-11-15 15:30:12.147280Z] Running run-tests step. 07:30:12 INFO - Running pre-action listener: _resource_record_pre_action 07:30:12 INFO - Running main action method: run_tests 07:30:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:30:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:30:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:30:12 INFO - 2016-11-15 07:30:12.161 screenresolution[1911:14768] starting screenresolution argv=screenresolution get 07:30:12 INFO - 2016-11-15 07:30:12.164 screenresolution[1911:14768] Display 0: 1600x1200x32@60 07:30:12 INFO - 2016-11-15 07:30:12.170 screenresolution[1912:14770] starting screenresolution argv=screenresolution list 07:30:12 INFO - Available Modes on Display 0 07:30:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:30:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:30:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:30:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:30:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:30:12 INFO - Intel Iris: 07:30:12 INFO - Chipset Model: Intel Iris 07:30:12 INFO - Type: GPU 07:30:12 INFO - Bus: Built-In 07:30:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:30:12 INFO - Vendor: Intel (0x8086) 07:30:12 INFO - Device ID: 0x0a2e 07:30:12 INFO - Revision ID: 0x0009 07:30:12 INFO - Displays: 07:30:12 INFO - Display: 07:30:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:30:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:30:12 INFO - Display Serial Number: 1600x1200 60 07:30:12 INFO - Main Display: Yes 07:30:12 INFO - Mirror: Off 07:30:12 INFO - Online: Yes 07:30:12 INFO - Rotation: Supported 07:30:12 INFO - Return code: 0 07:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:30:12 INFO - Python 2.7.3 07:30:12 INFO - Return code: 0 07:30:12 INFO - grabbing minidump binary from tooltool 07:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fcd30>, '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 0x103f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:30:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:30:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:30:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:30:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:30:12 INFO - Return code: 0 07:30:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:30:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:30:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:30:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:30:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 07:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --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 07:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOOQPxFmri/Render', 07:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:12 INFO - 'HOME': '/Users/cltbld', 07:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:12 INFO - 'LOGNAME': 'cltbld', 07:30:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:30:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:12 INFO - 'MOZ_NO_REMOTE': '1', 07:30:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:30:12 INFO - 'NO_EM_RESTART': '1', 07:30:12 INFO - 'PAGER': '/bin/cat', 07:30:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:12 INFO - 'PWD': '/builds/slave/test', 07:30:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:12 INFO - 'SHELL': '/bin/bash', 07:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArIM9InbaA/Listeners', 07:30:12 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:12 INFO - 'USER': 'cltbld', 07:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:12 INFO - 'XPC_FLAGS': '0x0', 07:30:12 INFO - 'XPC_SERVICE_NAME': '0', 07:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 07:30:12 INFO - mozversion application_buildid: 20161115050514 07:30:12 INFO - mozversion application_changeset: fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 07:30:12 INFO - mozversion application_display_name: Nightly 07:30:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:30:12 INFO - mozversion application_name: Firefox 07:30:12 INFO - mozversion application_remotingname: firefox 07:30:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:30:12 INFO - mozversion application_vendor: Mozilla 07:30:12 INFO - mozversion application_version: 53.0a1 07:30:12 INFO - mozversion platform_buildid: 20161115050514 07:30:12 INFO - mozversion platform_changeset: fb3473c1770d3dace1ec09dfbb574fabe7cb26b1 07:30:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:30:12 INFO - mozversion platform_version: 53.0a1 07:30:12 INFO - using testdate: 1479223812 07:30:12 INFO - actual date: 1479223812 07:30:12 INFO - starting webserver on 'localhost:51455' 07:30:12 INFO - SUITE-START | Running 2 tests 07:30:12 INFO - TEST-START | dromaeo_css 07:30:12 INFO - operating with platform_type : mac_ 07:30:12 INFO - Initialising browser for dromaeo_css test... 07:30:12 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpE3lyyD/profile http://localhost:51455/getInfo.html) 07:30:15 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 07:30:15 INFO - PROCESS | 1922 | colorDepth:24 07:30:15 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 07:30:15 INFO - PROCESS | 1922 | __metrics 07:30:15 INFO - PROCESS | 1922 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:30:15 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:30:16 INFO - TEST-INFO | 1922: exit 0 07:30:16 INFO - Browser initialized. 07:30:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:30:16 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpE3lyyD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:30:28 INFO - PROCESS | 1924 | RSS: Main: 238018560 07:30:28 INFO - PROCESS | 1924 | 07:31:19 INFO - PROCESS | 1924 | 1479223879787 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] 07:33:32 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51455/tests/dromaeo/cssquery-ext.html) 07:33:34 INFO - PROCESS | 1924 | RSS: Main: 363143168 07:33:34 INFO - PROCESS | 1924 | 07:36:37 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-ext.html (next: http://localhost:51455/tests/dromaeo/cssquery-jquery.html) 07:36:38 INFO - PROCESS | 1924 | RSS: Main: 375832576 07:36:38 INFO - PROCESS | 1924 | 07:39:40 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51455/tests/dromaeo/cssquery-mootools.html) 07:39:41 INFO - PROCESS | 1924 | RSS: Main: 345772032 07:39:41 INFO - PROCESS | 1924 | 07:42:43 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51455/tests/dromaeo/cssquery-prototype.html) 07:42:44 INFO - PROCESS | 1924 | RSS: Main: 342159360 07:42:44 INFO - PROCESS | 1924 | 07:45:47 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51455/tests/dromaeo/cssquery-yui.html) 07:45:48 INFO - PROCESS | 1924 | RSS: Main: 329256960 07:45:48 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51455/tests/dromaeo/cssquery-yui.html (next: http://localhost:51455/tests/dromaeo/cssquery-dojo.html) 07:48:51 INFO - PROCESS | 1924 | RSS: Main: 429654016 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | __start_tp_report 07:48:51 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 07:48:51 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 07:48:51 INFO - PROCESS | 1924 | |i|pagename|runs| 07:48:51 INFO - PROCESS | 1924 | |0;dojo.html;3345.8327083645827;3351.4302568563344;3356.8153751543655;3359.1434184283;3410.795352278862;5486.61593688441;5621.522170615503;5673.716314184289;5763.135529670546;5785.595008349417;5601.80393686221;5638.746256418461;5646.632968857021;5650.773969041234;5652.604317697763;5090.7200103994355;5121.615878809085;5132.101882170624;5160.148575485059;5167.6382653214105;5272.420033796279;5274.314339135907;5282.603804714642;5289.841304760844;5291.206319052135;4315.71947823392;5154.974225128888;5193.298904647881;5205;5278.762455689484;5268.604854635918;5275.340582427197;5304.151335786294;5325.1479763238085;5345.251664766935;5063.569596584295;5088.033273677989;5149.485051494858;5214.791408343662;5226.738663066832;5610.635308704962;5698.772049118031;5728.8854222915725;5731.168980497846;5758.452946970031;6172.629642221481;6173.783917562909;6182.041783523561;6185.041318595625;6187.721552530147;5170.302009228743;6069.969650151766;6115.523178280689;6178.845528861814;6212.316645169028;5755.913661295084;5784.739686714108;5788.363280039192;5806.535477161817;5878.147668588116;6259.047562194929;6289.685515724195;6297.401746834135;6304.747810087637;6305.5901366411035;5977.850553736104;6053.909191362133;6143.139960405547;6151.446369826737;6151.692415379213;6113.2330707408255;6126.853582554517;6133.854967165038;6137.468918203611;6242.438180563771;6183.752649894044;6191;6192.380761923771;6198.597091189058;6218.129461875341;5153.974230128825;6177.413145751237;6178.9495785715635;6228.065790131516;6230.221222347207;4783.091212669582;4884.5603895649565;4889.315495830587;4894.143524883131;4915.18899381598;4009.6190861868076;4319.956800432018;4438.1567502174485;4445.333200020023;4460.576245256695;5721.284839395075;5763.740631671585;5768.423157684282;5774.422557744192;5781.71091445426;6137.171481849982;6204.348543402878;6261.467775239064;6267.4986001119805;6298.1812364392335;5689.516391106719;5733.88532229353;5737.254156959652;5738.340090889601;5747.4252574742195;5328.17413300939;5337.599680024014;5344.171653393731;5348.759305831247;5352.785888564492;6219.40915613016;6267.0286105653695;6275.309715931153;6282.623042617366;6290.779822706183;5541.501264886179;5980.37206093354;6179;6246.406591373812;6272.153259309935;6354.586951848115;6360.872782544416;6365.586236894587;6384.233891932998;6393.680315984089;6191.19514463117;6210.161628180138;6213.813585592439;6221.47117495018;6224.346443623354;5076.873078173076;6114.266954506099;6129.509639228941;6138.202033735586;6164;5924.674142922181;5931.021381472181;5935.792247271497;5946.256717910262;5967.433093856077;5928.614640048383;5932.7033648318275;5937.7921772737745;5940.584159108821;5961.2846458425265;5259.55293800016;5759.683217423082;5783.624064435799;5800.332961709289;5865.794697185578;5998.790042348497;6122.886104953835;6134.227450118235;6146.385361463817;6152.354002829638;5409.621326507264;5479.547919801176;5923;5966.970165149147;5976.970115149396 07:48:51 INFO - PROCESS | 1924 | |1;ext.html;465.5414416799453;482.59220958290234;516.7519590596512;523.1838332201766;546.6556069676103;24415.63376549351;31586.368272634532;31594.368112637763;31614.89347872825;31646.36707265859;33750.493742593884;40625.59374406255;41423;43206;43214.567854321445;9300.860487092716;9313.906860931405;9390.389624674373;9421.293402994768;9473.57368918397;9374.953125234364;9476.66831660892;9487.383320084207;9488.952555237214;9496.002919693436;9469.100435458655;9495.477748723817;9497.477638729868;9498.572564234626;9514.048595140464;9394.248460123208;9464.006279340707;9493.66772162971;9495.477748723852;9509.239260859114;9266.583003764847;9338.95330523343;9347.766305842408;9349.906500934972;9372.718818435458;39128;39435.81692549228;39469.80265098656;39497.81506554808;39544.802275988724;3200.9519857201926;3359.277755282626;3431.330890476358;3643.2531331077066;3664.2305115925687;27906.441871162668;27915.975124477587;28186.859065704746;30252.09243722692;30365.696343036507;3225.048610659861;3242.238074052619;3288.276901267427;3329.1510664780326;3353.02762198962;477273;506580.4735621142;508407;510102;511906;55879;56193;56235.71882140563;56302.68065151504;56329.71835140798;59049.70475147683;59140.40859591435;59165.112523312186;59334.51663708356;59366.406335936954;13962.511312104027;15373.538793836204;15391.538253852403;15395.61510962235;15415.61460963485;11716.414179291172;11776.352300623377;11796;11824.940875295568;11840.230385025117;15159.545213643609;15211.847881521264;15217.6195595111;15222.847771522143;15433.691326173413;4961.751912404366;6358;6453.419192272718;6551.934480655228;6569.80290591283;775.4998026273022;836.8033512124646;1043.780806030742;1053.799778042185;1079.4945776539741;27123.728762712515;28682.282942926176;28829.42341153165;29702;29731;1026.1380440430075;1052.736815796051;1088.7931293054294;1117.402189828438;1137.294877176156;12086.637400877988;14739.263036848115;14966.700665986837;15329.46346877854;15397.461088861835;631645;638758;638818;638859.6399126102;640140;56865.43134568601;56901.71549142228;56936.153192340214;57124.14313785297;57133.42866571364;1211.357980270458;1248.3446190749928;1306.3664122900575;1352.60774375432;1374.8350197976306;28793.712062879942;29134;29930.85034574813;30065.248368790955;30684.539731904486;47278.29082563833;47690.76154619205;47844.521554784005;47889;47911.28133077937;399912;531191;533424;533921;534359;36955.81522092372;37398.439023414125;38175.6182438172;38374.23251535009;38470.23059538737;44928.775356124315;45115.323270150315;45569.31646025379;46139;46440.53559464362;30199.997000795247;31410;37161.814190928875;37619.435708464945;38909.80545097256;36753.448698270084;36823.26353472969;37614.811925940194;37844.432333514465;38003.239935201695;34595.4810677835;34671.95984120481;34955.47566786449;35299.470507941885;35781.28437431289;31528.684713152576;32775.67224327822;32999;33055.00834974954;33259.50110748292;24059.879700602087;24167.15414960468;24461.633075503527;25101.623475647513;25135.371615709755 07:48:51 INFO - PROCESS | 1924 | |2;jquery.html;340.6457284424199;358.7972795370615;361.58417819507565;368.756620630384;391.0497491096634;14333.713325733479;14601.269936503186;15416;16059.839401605981;19983.800161998377;18403.90798046008;19407.90296048518;20091.397258082245;20413.183472661076;20436.693449598268;5662.886742265152;6043.758249670008;6054.5459090568165;6057.848553786146;6059.757609695629;4146.1914926589325;4638.582527572517;5911.113333000041;5972.074328479092;6042.909356359658;4381.824727010931;4422.911541769163;6294.2761582417925;6648.601083934956;6661.533692641517;6345.365463453664;6548.214214294268;6618.900716489208;6692.63190524521;6731.427828634596;5723.713814309268;5728.598998070137;5731.684757338345;5739.483446489836;5750.769969201227;24279.878600606884;25225;25301.873490632614;25309.367265818317;25589.848456819487;2207.293666026872;2329.9650505242435;2368.9644655330185;2716.994711956569;3067.1105379439937;14928.552143435714;15034.69930601382;15597.610059748484;15619.453319133776;15669.373225071098;1809;1828.1042289278291;1861.8603604729565;1869.8130186981193;1994.670879304913;68207.31792682059;103875;105768;107681;111906.44046779795;10278.691639250834;10512.26414151002;10681.519331630096;10767.061928852881;10892.346459212473;13423.865761342457;13428.530001450097;13447.125936814076;13461.730765384638;13477.797833032513;6865;7979.241972012649;8031.558264295403;8090.757277281691;8452.915470845335;6845.261895241943;8163.469374490639;8234.835303294021;8385.454945428528;8403.579821008925;6451.516136289703;6555.836104097436;6563.343665633399;6592.110065141241;6607;1645.7449095390234;1770.371518111052;1806.927722891096;1818.1091265280181;1825.9360922367653;810.6433169405443;846.386369881843;873.6811063961608;909.658254075247;960.5869476125333;16212.594685132966;20327.491872174993;22206.555868882533;22268.888655556944;22273.443163920707;916.2944532709777;929.4423345992485;940.4451373689419;970.2189737261431;975.2198241406988;11408.201425900108;11630.476628551736;11661.766764664659;11666.591669291534;11679.532818687327;532043;537423;553963;571525;572490;59717.40282597205;62286.44283892939;70794;99507;101373.49313253387;927.2535608834953;936.5644975086617;953.952302384878;962.2879069488528;1009.1422291052546;15915.204239787732;17889.642207156045;18540.16569254414;18547.81452185461;19056.714149287494;45076.32385514286;45366;45901.31148032715;46040;46770.76614617041;62949;63829.68085159545;64063.7187256243;64580;66020.33979660142;7056.506044577036;7389.261073892782;7744.535327880345;8116.350691944748;8432.494050356998;13129.671758205739;13145.145565538209;13287.269200193696;13409.463621455032;13579.864201358256;11284.32294062359;11481.36852473122;11555.2489088209;11629.127815414047;11810.40947952616;10395.94802025985;11023.503942322775;11136.276142050741;11358.318500889973;11495.655130346104;11474.311541307548;11525.494629171253;11648.184627076362;11688.064954803425;11840;7945.682172713026;7974.84050319002;8278.586070696321;8373.288270497076;8387.538685372363;10087.445190514592;10708.196885233794;11010.834837477283;11380.54477820878;11667.42489763871 07:48:51 INFO - PROCESS | 1924 | |3;mootools.html;1455.5633310007;1497.595649174723;1654.7104256755065;1741.1555395633109;1829.7438358629795;7974.920250797491;8406.66373345067;8421.326293896489;8441.155884411555;8447.23974842264;7332.19345871955;7584.620768961558;7589;7656.846863062749;8295.958520207405;1735.297204632126;2050.3643870400224;2067.762207346152;2087.9373618791383;2276.9658455123185;2299.7240331160283;2310.364649721323;2314.490812021361;2314.9884250578725;2315.4327189838427;1873.2319748902953;2224.399412158716;2262.9207977720785;2283.7145356830397;2287.3938406322336;2247.325802259316;2250.1211488407034;2272.1820144747876;2272.863628182314;2277.7836105569945;2275.7382900966436;2303.884805759705;2305.1586171047616;2306.411864974438;2307.792298693109;8239.588020599005;8260.586970651504;8379.994400671898;8381.203785640355;8396.160383961616;4967.453580106186;5142.897142057138;5143.871403214912;5147.22791581262;5166.870828229286;3262.8531716072835;3369.730421566269;3427.640097789721;3609.4946707460977;3630.110622897381;817.5952903312839;1040.9017841195512;1070.9410982395966;1078.1267173589417;1085.484394912418;10636.62771802991;10705.785884282272;10729.785404291948;10742.570297188104;10744.516496757664;4382.583654552876;4398.318260669601;4483.730976141442;4495.752733599618;4606.332081848113;9409.247260219166;9422.858657120149;9435.245180385553;9459.148676619137;9460;5048.646594738333;5133.717645529457;5143.3827940646615;5156.690598564098;5159.277701121846;4668.626509879202;4672.789724462407;4699.201135806853;4718.764061796896;4729.763511824395;3342.1644588852787;3387.085486918518;3399.2861499084975;3446.965530344715;3508.456189290664;1072.5602502973743;1123.7471568896933;1220.4690959432676;1239.2192918461492;1240.5472002719123;864.3981615337752;877.6966205185295;880.4673172730534;899.6266549382065;916.1113719691888;5417.972910135503;5457.103231611226;5632.690202038927;5707.914381284201;5718.25662663851;875.65411662393;888.7733627924838;929.7722058095675;936.3773090894615;961.2838435365558;3527.470879368115;3724.255148970217;3861;3893.3381325174787;3895.35726605107;9638.42169469834;9855.01449855023;9917.851232231522;10081.747956301151;10082.69751907444;9358.953205233931;9865.408075515348;10027.448490333101;10036.849447258297;10056.949715251378;7483.32650061497;7625.923740762521;7627.4279429043045;7665.540067595962;7754.961225193725;5557.833265002057;5591.552675786009;5637.661740295595;5826.213461182686;5897.498712609305;9246.445213287225;9350.485723285285;9373.062693730844;9380.718578442658;9390.436573805593;9233.122853328923;9334.879814422313;9445.669401570913;9456.101670341306;9522.0954009369;6726.260111387841;6947.513674042769;7045.753398631024;7061.6822243000215;7219.386352160125;7093.432525398059;7761.495502792301;7864.2922137007945;7868.055833299812;7869.803254918444;6529.836754081186;7030.543014704028;7109.964450177716;7180.138383393817;7193.172785129568;6878.793636190922;6958.547694399847;6965.756198533027;6967.75612853568;7020.262872398324;6528.706208220547;6685.2646208916;6688.197416310074;6688.765893193715;6722.798316050526;4047.4333593296374;4514.25514790056;4649.372334734768;4676.719396836201;4709.199436095941;4730.266808644666;4755.785989630405;4817.229243321051;4885.584725298248;5040.798368065236 07:48:51 INFO - PROCESS | 1924 | |4;prototype.html;255.71743223737775;290.1440749788125;292.76871271695364;300.4276852595804;305.18515563445595;5000.39995200576;5129.871753206167;5162.690238585678;5182.714950677711;5195.090859099661;4248.957510424895;4838.588719958808;4910.386201724785;5026.472220416849;5062.645614806965;3431.1593659553446;3894.3963685628773;3919.6668283195963;3948.1708841143395;3983.6813054955533;3912.4522566840665;3927.607239276064;3942.7042971777087;3953.604639536052;3981.4226937094104;3792.5259342582176;3906.3554513505237;3916.3929590913453;3925.9803700981456;3943.5662077171633;3879.612038796126;3913.66733827625;3915.3931140672958;3919.529656441245;3936.350502167139;3407.8807241746667;3428.862845486178;3431.725461963037;3431.9142021449416;3435.862565497377;4420.215264383418;4649.1399091167905;4935.185694360463;4982.832467876486;5007.624428167902;4229.344451610005;4411.250087485135;4458.9554104459185;4498.550144985508;4511.526289739563;4461.888452788674;5321.148616221425;5327.413984461706;5395.843431592523;5407.188921661743;3712.777233366007;3721.93432644882;3736.2527494500937;3740.233252183296;3743.700503959704;4870.488598697121;5349.839504814861;5489.945100548984;5558.77764889408;5560.304961879725;3300.504924261332;3429.8628054877527;3831.8084095795098;3850.268448994682;3853.460515527828;3427.8286085695618;3674.797886116289;3862;3915.8477425672036;3916.2559113768293;3700.6114357992024;4410.919324765453;4499.280115181556;4500.17485137637;4519.435070616173;4951.356323677899;5148;5315.176147697113;5447.4824891635235;5487.670739755627;1025.610268098118;1058.8888166742536;1219.1283232488813;1220.6460126563197;1222.468226321553;779.5400713578903;790.3005839831791;798.7124635131348;812.3095368936475;845.5222799118478;3917.7061720371084;4779.665423420397;4780.49804770501;4788.257820037899;4805.951940480621;4711.693739906964;5192.688438693691;5244.816431424882;5246.976839516257;5319.946800532022;3681.6686498214754;3755.549334079928;4750.786214620351;4785.282207668808;4795.808167673254;2289.6794448777187;2333.1484008337175;2744.7118052604587;2747.3543717226435;2747.7880306353577;4941.950580494221;5327.987682340343;5447;5452.509274165344;5468.097763868917;3811.7331786774657;4376.6498680105515;4444.244613301755;4991.7304165771475;5222.92165617516;4588.655850811202;4667.953320466752;5348.823258882961;5380.7309634518115;5443.727813609304;4051.331530297468;4785.353977213171;5171.81898633546;5186.014657215117;5196.272521846869;4908.410990681141;5150.690958542499;5167.870803229799;5555.694436806015;5622.24099746529;5036.269740887477;5728.312602487728;5750.25246717924;5765.8558536036935;5786.42135786418;3743.2513497300106;3775;3786.242751449666;3790.374588192917;3813.1801662642665;4533.750643714628;4840.540148685869;5582.218489411404;5650.773969041356;5674;4639.960825659198;4730.503297153749;5387.81142660005;5396.298481197419;5422.15956526739;4874.878128046827;5293.735313234246;5396.595255355943;5413.945860541344;5419.620626556103;4264.232438161161;5316.043112239679;5328.973355133199;5345.4654534546235;5404.83785486436;4574.8791546071425;5090.949090509195;5151.871203219949;5346.866328341823;5405.594580406407;4535.79588918506;4981.455748717909;5277.8416647500635;5284.894302114013;5288.762005709829 07:48:51 INFO - PROCESS | 1924 | |5;yui.html;538.9029974604571;704.5350068954488;733.809209605503;744.1888341707541;756.5422919133922;13718.245496497688;14443.277836108178;14483.637909052279;14501.637459063528;14514.782278265835;10963.342199468038;12464.252144871314;12474.251544907289;12482.687932801684;12485.25088494691;647.3688154049787;668.612204921147;797.4417907464768;806.8466991271669;823.6005537314397;817.7424111404888;821.7247222180577;827.1604321613537;828.714093637697;828.7223780033696;877.0615441477621;877.4603618774472;879.2921698033081;880.1462581296132;880.5333173418098;681.7784220128428;705.5202462325617;709.6522703875115;874.8600223964168;875.6978842299396;523.0950455711602;523.1498814426557;631.7251995381978;650.7787352300194;654.1136759690646;13636.590902272848;13670.248136352497;13686.31568421575;13690.178589284675;13698.38357273925;12269.386530673431;12437.689057773538;12558.257774764861;12642.683932901658;12893.161944473579;11658.766824663458;11727.354995475245;11820.29078255299;11837.940810295893;12160;4258.723182993126;4261.893452663678;4364.978175109104;4443.533428989942;4443.888902777424;51088.74455627735;73833.14002319702;74105.6294718523;77584.22415775826;79634.60182699049;10055.346402483816;10100.949495252476;10107.39355638664;10248.948755256177;10294.124999374988;19063.332783352795;19069.237230510902;19074.618507630046;19121.808781912;19192.520186995156;7049.823754406181;7174.246704096099;7189.065421495172;7259.673314700851;7418.591977441185;3983.462232598562;3990.900227494336;4142.979285103555;4145.129522800185;4165.958340416557;3070.201747545654;3107.2697915989725;3159.4786860168274;3171.3815805917625;3182.6180858297153;527.380328114464;616.0851136062943;616.4236438929615;619.4579742725116;636.1539152926597;537.5994883811585;584.9385814489502;595.8838026584862;597.9910301345483;597.9970100149471;8432;8466.365022623328;8474.957625211835;8487.787805304917;8761.211490965843;489.9216125419916;547.4771593128633;550.8925759476944;571.3401021819722;575.6920047774421;2824.7457728804097;3368.360011597789;3925.8429662813737;3997.300472417315;4166.791660416967;72949.90575141377;78681.60659196667;104546;108624;117292;86335;86896;88312;89826.65260021105;91160;50374.236901708995;68985.65507172432;85321.57339213388;86092.56953715191;89407.55296223608;6410.839729006812;7697.0378702662165;7810;7920.247576480225;8080.595970201584;39062.80468597753;54264;55341.33975980727;56516;56562.71718641381;59260;77062.2293777055;78838;81488.59255703684;83409.58295208485;10622.8937710625;10661.307015043998;10966.396848173426;10983.39591322485;11222.270552414067;9847.60609575609;11430.885691143314;11694.415279236175;11770.529178833094;11896.107791915469;11009.25676282923;11058.502367393326;11201.943990279997;11203.21577489568;11376;9481.754368496982;12608.369581520703;12626.684332891751;12903.483860645469;13035;10982.725431864268;12564.246145231315;12658.493660253487;12662.683432914251;13310.467581297027;8875.689350872688;10511.632092876713;10971.177161713063;10993.61522346714;11077.279976801483;10716.410597417216;10752;10856.294340867818;11008;11055.72360690989 07:48:51 INFO - PROCESS | 1924 | __end_tp_report 07:48:51 INFO - PROCESS | 1924 | __start_cc_report 07:48:51 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,1894 07:48:51 INFO - PROCESS | 1924 | __end_cc_report 07:48:51 INFO - PROCESS | 1924 | __startTimestamp1479224931797__endTimestamp 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | ------- Summary: start ------- 07:48:51 INFO - PROCESS | 1924 | Number of tests: 6 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#0] dojo.html Cycles:165 Average:5680.77 Median:5800.33 stddev:642.70 (11.1%) stddev-sans-first:618.02 07:48:51 INFO - PROCESS | 1924 | Values: 3345.8 3351.4 3356.8 3359.1 3410.8 5486.6 5621.5 5673.7 5763.1 5785.6 5601.8 5638.7 5646.6 5650.8 5652.6 5090.7 5121.6 5132.1 5160.1 5167.6 5272.4 5274.3 5282.6 5289.8 5291.2 4315.7 5155.0 5193.3 5205.0 5278.8 5268.6 5275.3 5304.2 5325.1 5345.3 5063.6 5088.0 5149.5 5214.8 5226.7 5610.6 5698.8 5728.9 5731.2 5758.5 6172.6 6173.8 6182.0 6185.0 6187.7 5170.3 6070.0 6115.5 6178.8 6212.3 5755.9 5784.7 5788.4 5806.5 5878.1 6259.0 6289.7 6297.4 6304.7 6305.6 5977.9 6053.9 6143.1 6151.4 6151.7 6113.2 6126.9 6133.9 6137.5 6242.4 6183.8 6191.0 6192.4 6198.6 6218.1 5154.0 6177.4 6178.9 6228.1 6230.2 4783.1 4884.6 4889.3 4894.1 4915.2 4009.6 4320.0 4438.2 4445.3 4460.6 5721.3 5763.7 5768.4 5774.4 5781.7 6137.2 6204.3 6261.5 6267.5 6298.2 5689.5 5733.9 5737.3 5738.3 5747.4 5328.2 5337.6 5344.2 5348.8 5352.8 6219.4 6267.0 6275.3 6282.6 6290.8 5541.5 5980.4 6179.0 6246.4 6272.2 6354.6 6360.9 6365.6 6384.2 6393.7 6191.2 6210.2 6213.8 6221.5 6224.3 5076.9 6114.3 6129.5 6138.2 6164.0 5924.7 5931.0 5935.8 5946.3 5967.4 5928.6 5932.7 5937.8 5940.6 5961.3 5259.6 5759.7 5783.6 5800.3 5865.8 5998.8 6122.9 6134.2 6146.4 6152.4 5409.6 5479.5 5923.0 5967.0 5977.0 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#1] ext.html Cycles:180 Average:67599.67 Median:38274.93 stddev:147967.22 (386.6%) stddev-sans-first:148296.45 07:48:51 INFO - PROCESS | 1924 | Values: 465.5 482.6 516.8 523.2 546.7 24415.6 31586.4 31594.4 31614.9 31646.4 33750.5 40625.6 41423.0 43206.0 43214.6 9300.9 9313.9 9390.4 9421.3 9473.6 9375.0 9476.7 9487.4 9489.0 9496.0 9469.1 9495.5 9497.5 9498.6 9514.0 9394.2 9464.0 9493.7 9495.5 9509.2 9266.6 9339.0 9347.8 9349.9 9372.7 39128.0 39435.8 39469.8 39497.8 39544.8 3201.0 3359.3 3431.3 3643.3 3664.2 27906.4 27916.0 28186.9 30252.1 30365.7 3225.0 3242.2 3288.3 3329.2 3353.0 477273.0 506580.5 508407.0 510102.0 511906.0 55879.0 56193.0 56235.7 56302.7 56329.7 59049.7 59140.4 59165.1 59334.5 59366.4 13962.5 15373.5 15391.5 15395.6 15415.6 11716.4 11776.4 11796.0 11824.9 11840.2 15159.5 15211.8 15217.6 15222.8 15433.7 4961.8 6358.0 6453.4 6551.9 6569.8 775.5 836.8 1043.8 1053.8 1079.5 27123.7 28682.3 28829.4 29702.0 29731.0 1026.1 1052.7 1088.8 1117.4 1137.3 12086.6 14739.3 14966.7 15329.5 15397.5 631645.0 638758.0 638818.0 638859.6 640140.0 56865.4 56901.7 56936.2 57124.1 57133.4 1211.4 1248.3 1306.4 1352.6 1374.8 28793.7 29134.0 29930.9 30065.2 30684.5 47278.3 47690.8 47844.5 47889.0 47911.3 399912.0 531191.0 533424.0 533921.0 534359.0 36955.8 37398.4 38175.6 38374.2 38470.2 44928.8 45115.3 45569.3 46139.0 46440.5 30200.0 31410.0 37161.8 37619.4 38909.8 36753.4 36823.3 37614.8 37844.4 38003.2 34595.5 34672.0 34955.5 35299.5 35781.3 31528.7 32775.7 32999.0 33055.0 33259.5 24059.9 24167.2 24461.6 25101.6 25135.4 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#2] jquery.html Cycles:180 Average:31335.90 Median:1703.88 stddev:91247.96 (5355.3%) stddev-sans-first:91474.26 07:48:51 INFO - PROCESS | 1924 | Values: 340.6 358.8 361.6 368.8 391.0 14333.7 14601.3 15416.0 16059.8 19983.8 18403.9 19407.9 20091.4 20413.2 20436.7 5662.9 6043.8 6054.5 6057.8 6059.8 4146.2 4638.6 5911.1 5972.1 6042.9 4381.8 4422.9 6294.3 6648.6 6661.5 6345.4 6548.2 6618.9 6692.6 6731.4 5723.7 5728.6 5731.7 5739.5 5750.8 24279.9 25225.0 25301.9 25309.4 25589.8 2207.3 2330.0 2369.0 2717.0 3067.1 14928.6 15034.7 15597.6 15619.5 15669.4 1809.0 1828.1 1861.9 1869.8 1994.7 68207.3 103875.0 105768.0 107681.0 111906.4 10278.7 10512.3 10681.5 10767.1 10892.3 13423.9 13428.5 13447.1 13461.7 13477.8 6865.0 7979.2 8031.6 8090.8 8452.9 6845.3 8163.5 8234.8 8385.5 8403.6 6451.5 6555.8 6563.3 6592.1 6607.0 1645.7 1770.4 1806.9 1818.1 1825.9 810.6 846.4 873.7 909.7 960.6 16212.6 20327.5 22206.6 22268.9 22273.4 916.3 929.4 940.4 970.2 975.2 11408.2 11630.5 11661.8 11666.6 11679.5 532043.0 537423.0 553963.0 571525.0 572490.0 59717.4 62286.4 70794.0 99507.0 101373.5 927.3 936.6 954.0 962.3 1009.1 15915.2 17889.6 18540.2 18547.8 19056.7 45076.3 45366.0 45901.3 46040.0 46770.8 62949.0 63829.7 64063.7 64580.0 66020.3 7056.5 7389.3 7744.5 8116.4 8432.5 13129.7 13145.1 13287.3 13409.5 13579.9 11284.3 11481.4 11555.2 11629.1 11810.4 10395.9 11023.5 11136.3 11358.3 11495.7 11474.3 11525.5 11648.2 11688.1 11840.0 7945.7 7974.8 8278.6 8373.3 8387.5 10087.4 10708.2 11010.8 11380.5 11667.4 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#3] mootools.html Cycles:180 Average:5373.98 Median:5151.96 stddev:2962.76 (57.5%) stddev-sans-first:2956.44 07:48:51 INFO - PROCESS | 1924 | Values: 1455.6 1497.6 1654.7 1741.2 1829.7 7974.9 8406.7 8421.3 8441.2 8447.2 7332.2 7584.6 7589.0 7656.8 8296.0 1735.3 2050.4 2067.8 2087.9 2277.0 2299.7 2310.4 2314.5 2315.0 2315.4 1873.2 2224.4 2262.9 2283.7 2287.4 2247.3 2250.1 2272.2 2272.9 2277.8 2275.7 2303.9 2305.2 2306.4 2307.8 8239.6 8260.6 8380.0 8381.2 8396.2 4967.5 5142.9 5143.9 5147.2 5166.9 3262.9 3369.7 3427.6 3609.5 3630.1 817.6 1040.9 1070.9 1078.1 1085.5 10636.6 10705.8 10729.8 10742.6 10744.5 4382.6 4398.3 4483.7 4495.8 4606.3 9409.2 9422.9 9435.2 9459.1 9460.0 5048.6 5133.7 5143.4 5156.7 5159.3 4668.6 4672.8 4699.2 4718.8 4729.8 3342.2 3387.1 3399.3 3447.0 3508.5 1072.6 1123.7 1220.5 1239.2 1240.5 864.4 877.7 880.5 899.6 916.1 5418.0 5457.1 5632.7 5707.9 5718.3 875.7 888.8 929.8 936.4 961.3 3527.5 3724.3 3861.0 3893.3 3895.4 9638.4 9855.0 9917.9 10081.7 10082.7 9359.0 9865.4 10027.4 10036.8 10056.9 7483.3 7625.9 7627.4 7665.5 7755.0 5557.8 5591.6 5637.7 5826.2 5897.5 9246.4 9350.5 9373.1 9380.7 9390.4 9233.1 9334.9 9445.7 9456.1 9522.1 6726.3 6947.5 7045.8 7061.7 7219.4 7093.4 7761.5 7864.3 7868.1 7869.8 6529.8 7030.5 7110.0 7180.1 7193.2 6878.8 6958.5 6965.8 6967.8 7020.3 6528.7 6685.3 6688.2 6688.8 6722.8 4047.4 4514.3 4649.4 4676.7 4709.2 4730.3 4755.8 4817.2 4885.6 5040.8 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#4] prototype.html Cycles:180 Average:4275.34 Median:4765.23 stddev:1302.40 (27.3%) stddev-sans-first:1270.63 07:48:51 INFO - PROCESS | 1924 | Values: 255.7 290.1 292.8 300.4 305.2 5000.4 5129.9 5162.7 5182.7 5195.1 4249.0 4838.6 4910.4 5026.5 5062.6 3431.2 3894.4 3919.7 3948.2 3983.7 3912.5 3927.6 3942.7 3953.6 3981.4 3792.5 3906.4 3916.4 3926.0 3943.6 3879.6 3913.7 3915.4 3919.5 3936.4 3407.9 3428.9 3431.7 3431.9 3435.9 4420.2 4649.1 4935.2 4982.8 5007.6 4229.3 4411.3 4459.0 4498.6 4511.5 4461.9 5321.1 5327.4 5395.8 5407.2 3712.8 3721.9 3736.3 3740.2 3743.7 4870.5 5349.8 5489.9 5558.8 5560.3 3300.5 3429.9 3831.8 3850.3 3853.5 3427.8 3674.8 3862.0 3915.8 3916.3 3700.6 4410.9 4499.3 4500.2 4519.4 4951.4 5148.0 5315.2 5447.5 5487.7 1025.6 1058.9 1219.1 1220.6 1222.5 779.5 790.3 798.7 812.3 845.5 3917.7 4779.7 4780.5 4788.3 4806.0 4711.7 5192.7 5244.8 5247.0 5319.9 3681.7 3755.5 4750.8 4785.3 4795.8 2289.7 2333.1 2744.7 2747.4 2747.8 4942.0 5328.0 5447.0 5452.5 5468.1 3811.7 4376.6 4444.2 4991.7 5222.9 4588.7 4668.0 5348.8 5380.7 5443.7 4051.3 4785.4 5171.8 5186.0 5196.3 4908.4 5150.7 5167.9 5555.7 5622.2 5036.3 5728.3 5750.3 5765.9 5786.4 3743.3 3775.0 3786.2 3790.4 3813.2 4533.8 4840.5 5582.2 5650.8 5674.0 4640.0 4730.5 5387.8 5396.3 5422.2 4874.9 5293.7 5396.6 5413.9 5419.6 4264.2 5316.0 5329.0 5345.5 5404.8 4574.9 5090.9 5151.9 5346.9 5405.6 4535.8 4981.5 5277.8 5284.9 5288.8 07:48:51 INFO - PROCESS | 1924 | 07:48:51 INFO - PROCESS | 1924 | [#5] yui.html Cycles:180 Average:18848.58 Median:2466.91 stddev:27423.34 (1111.6%) stddev-sans-first:27465.80 07:48:51 INFO - PROCESS | 1924 | Values: 538.9 704.5 733.8 744.2 756.5 13718.2 14443.3 14483.6 14501.6 14514.8 10963.3 12464.3 12474.3 12482.7 12485.3 647.4 668.6 797.4 806.8 823.6 817.7 821.7 827.2 828.7 828.7 877.1 877.5 879.3 880.1 880.5 681.8 705.5 709.7 874.9 875.7 523.1 523.1 631.7 650.8 654.1 13636.6 13670.2 13686.3 13690.2 13698.4 12269.4 12437.7 12558.3 12642.7 12893.2 11658.8 11727.4 11820.3 11837.9 12160.0 4258.7 4261.9 4365.0 4443.5 4443.9 51088.7 73833.1 74105.6 77584.2 79634.6 10055.3 10100.9 10107.4 10248.9 10294.1 19063.3 19069.2 19074.6 19121.8 19192.5 7049.8 7174.2 7189.1 7259.7 7418.6 3983.5 3990.9 4143.0 4145.1 4166.0 3070.2 3107.3 3159.5 3171.4 3182.6 527.4 616.1 616.4 619.5 636.2 537.6 584.9 595.9 598.0 598.0 8432.0 8466.4 8475.0 8487.8 8761.2 489.9 547.5 550.9 571.3 575.7 2824.7 3368.4 3925.8 3997.3 4166.8 72949.9 78681.6 104546.0 108624.0 117292.0 86335.0 86896.0 88312.0 89826.7 91160.0 50374.2 68985.7 85321.6 86092.6 89407.6 6410.8 7697.0 7810.0 7920.2 8080.6 39062.8 54264.0 55341.3 56516.0 56562.7 59260.0 77062.2 78838.0 81488.6 83409.6 10622.9 10661.3 10966.4 10983.4 11222.3 9847.6 11430.9 11694.4 11770.5 11896.1 11009.3 11058.5 11201.9 11203.2 11376.0 9481.8 12608.4 12626.7 12903.5 13035.0 10982.7 12564.2 12658.5 12662.7 13310.5 8875.7 10511.6 10971.2 10993.6 11077.3 10716.4 10752.0 10856.3 11008.0 11055.7 07:48:51 INFO - PROCESS | 1924 | -------- Summary: end -------- 07:48:51 INFO - PROCESS | 1924 | 07:48:52 INFO - TEST-INFO | 1924: exit 0 07:48:52 INFO - TEST-OK | dromaeo_css | took 1119712ms 07:48:52 INFO - TEST-START | kraken 07:48:52 INFO - operating with platform_type : mac_ 07:48:52 INFO - Initialising browser for kraken test... 07:48:52 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmphqMcJY/profile http://localhost:51455/getInfo.html) 07:48:54 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 07:48:54 INFO - PROCESS | 1927 | colorDepth:24 07:48:54 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 07:48:54 INFO - PROCESS | 1927 | __metrics 07:48:54 INFO - PROCESS | 1927 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:48:54 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:48:55 INFO - TEST-INFO | 1927: exit 0 07:48:55 INFO - Browser initialized. 07:48:55 INFO - Running cycle 1/1 for kraken test... 07:48:55 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmphqMcJY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:49:07 INFO - PROCESS | 1928 | RSS: Main: 238002176 07:49:07 INFO - PROCESS | 1928 | 07:49:10 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51455/tests/kraken/driver.html?testName=audio-beat-detection) 07:49:11 INFO - PROCESS | 1928 | RSS: Main: 289312768 07:49:11 INFO - PROCESS | 1928 | 07:49:14 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51455/tests/kraken/driver.html?testName=audio-dft) 07:49:14 INFO - PROCESS | 1928 | RSS: Main: 289370112 07:49:14 INFO - PROCESS | 1928 | 07:49:18 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51455/tests/kraken/driver.html?testName=audio-fft) 07:49:18 INFO - PROCESS | 1928 | RSS: Main: 382550016 07:49:18 INFO - PROCESS | 1928 | 07:49:20 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51455/tests/kraken/driver.html?testName=audio-oscillator) 07:49:21 INFO - PROCESS | 1928 | RSS: Main: 281587712 07:49:21 INFO - PROCESS | 1928 | 07:49:23 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51455/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:49:24 INFO - PROCESS | 1928 | RSS: Main: 286056448 07:49:24 INFO - PROCESS | 1928 | 07:49:26 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51455/tests/kraken/driver.html?testName=imaging-darkroom) 07:49:27 INFO - PROCESS | 1928 | RSS: Main: 328036352 07:49:27 INFO - PROCESS | 1928 | 07:49:30 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51455/tests/kraken/driver.html?testName=imaging-desaturate) 07:49:31 INFO - PROCESS | 1928 | RSS: Main: 330006528 07:49:31 INFO - PROCESS | 1928 | 07:49:33 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51455/tests/kraken/driver.html?testName=json-parse-financial) 07:49:34 INFO - PROCESS | 1928 | RSS: Main: 327811072 07:49:34 INFO - PROCESS | 1928 | 07:49:36 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51455/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:49:36 INFO - PROCESS | 1928 | RSS: Main: 303730688 07:49:36 INFO - PROCESS | 1928 | 07:49:38 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:49:39 INFO - PROCESS | 1928 | RSS: Main: 305090560 07:49:39 INFO - PROCESS | 1928 | 07:49:41 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:49:42 INFO - PROCESS | 1928 | RSS: Main: 312709120 07:49:42 INFO - PROCESS | 1928 | 07:49:45 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:49:46 INFO - PROCESS | 1928 | RSS: Main: 327155712 07:49:46 INFO - PROCESS | 1928 | 07:49:49 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:49:49 INFO - PROCESS | 1928 | RSS: Main: 321253376 07:49:49 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51455/tests/kraken/driver.html?testName=ai-astar) 07:49:51 INFO - PROCESS | 1928 | RSS: Main: 365060096 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | __start_tp_report 07:49:51 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 07:49:51 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 07:49:51 INFO - PROCESS | 1928 | |i|pagename|runs| 07:49:51 INFO - PROCESS | 1928 | |0;ai-astar;114;114;115;114;112;114;113;113;120;164 07:49:51 INFO - PROCESS | 1928 | |1;audio-beat-detection;152;114;114;150;115;113;113;111;148;111 07:49:51 INFO - PROCESS | 1928 | |2;audio-dft;184;185;180;185;185;181;192;182;182;184 07:49:51 INFO - PROCESS | 1928 | |3;audio-fft;74;59;60;89;63;62;59;59;82;57 07:49:51 INFO - PROCESS | 1928 | |4;audio-oscillator;86;70;92;71;70;69;90;68;69;93 07:49:51 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;91;88;91;88;90;87;91;87;90;87 07:49:51 INFO - PROCESS | 1928 | |6;imaging-darkroom;117;162;122;98;98;97;99;98;97;97 07:49:51 INFO - PROCESS | 1928 | |7;imaging-desaturate;87;85;88;86;85;89;85;87;86;84 07:49:51 INFO - PROCESS | 1928 | |8;json-parse-financial;54;54;55;53;55;55;57;55;54;55 07:49:51 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;66;73;71;76;71;70;71;71;70;73 07:49:51 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;92;88;87;86;91;87;87;90;88;93 07:49:51 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;157;137;153;166;158;140;150;166;212;153 07:49:51 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;174;176;175;175;176;189;234;200;175;174 07:49:51 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;69;67;69;68;68;66;67;67;69;85 07:49:51 INFO - PROCESS | 1928 | __end_tp_report 07:49:51 INFO - PROCESS | 1928 | __start_cc_report 07:49:51 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,2010 07:49:51 INFO - PROCESS | 1928 | __end_cc_report 07:49:51 INFO - PROCESS | 1928 | __startTimestamp1479224991771__endTimestamp 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | ------- Summary: start ------- 07:49:51 INFO - PROCESS | 1928 | Number of tests: 14 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:119.30 Median:114.00 stddev:15.85 (13.9%) stddev-sans-first:16.70 07:49:51 INFO - PROCESS | 1928 | Values: 114.0 114.0 115.0 114.0 112.0 114.0 113.0 113.0 120.0 164.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:114.50 stddev:17.94 (15.7%) stddev-sans-first:15.94 07:49:51 INFO - PROCESS | 1928 | Values: 152.0 114.0 114.0 150.0 115.0 113.0 113.0 111.0 148.0 111.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:3.33 (1.8%) stddev-sans-first:3.54 07:49:51 INFO - PROCESS | 1928 | Values: 184.0 185.0 180.0 185.0 185.0 181.0 192.0 182.0 182.0 184.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.50 stddev:11.24 (18.0%) stddev-sans-first:11.58 07:49:51 INFO - PROCESS | 1928 | Values: 74.0 59.0 60.0 89.0 63.0 62.0 59.0 59.0 82.0 57.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.50 stddev:10.89 (13.9%) stddev-sans-first:11.14 07:49:51 INFO - PROCESS | 1928 | Values: 86.0 70.0 92.0 71.0 70.0 69.0 90.0 68.0 69.0 93.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 07:49:51 INFO - PROCESS | 1928 | Values: 91.0 88.0 91.0 88.0 90.0 87.0 91.0 87.0 90.0 87.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:97.50 stddev:20.91 (21.4%) stddev-sans-first:21.95 07:49:51 INFO - PROCESS | 1928 | Values: 117.0 162.0 122.0 98.0 98.0 97.0 99.0 98.0 97.0 97.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.50 stddev:1.55 (1.8%) stddev-sans-first:1.62 07:49:51 INFO - PROCESS | 1928 | Values: 87.0 85.0 88.0 86.0 85.0 89.0 85.0 87.0 86.0 84.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.09 07:49:51 INFO - PROCESS | 1928 | Values: 54.0 54.0 55.0 53.0 55.0 55.0 57.0 55.0 54.0 55.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:2.57 (3.6%) stddev-sans-first:1.92 07:49:51 INFO - PROCESS | 1928 | Values: 66.0 73.0 71.0 76.0 71.0 70.0 71.0 71.0 70.0 73.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.00 stddev:2.42 (2.7%) stddev-sans-first:2.30 07:49:51 INFO - PROCESS | 1928 | Values: 92.0 88.0 87.0 86.0 91.0 87.0 87.0 90.0 88.0 93.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:159.20 Median:157.50 stddev:20.84 (13.2%) stddev-sans-first:22.09 07:49:51 INFO - PROCESS | 1928 | Values: 157.0 137.0 153.0 166.0 158.0 140.0 150.0 166.0 212.0 153.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:176.00 stddev:19.28 (11.0%) stddev-sans-first:20.05 07:49:51 INFO - PROCESS | 1928 | Values: 174.0 176.0 175.0 175.0 176.0 189.0 234.0 200.0 175.0 174.0 07:49:51 INFO - PROCESS | 1928 | 07:49:51 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.50 stddev:5.54 (8.1%) stddev-sans-first:5.88 07:49:51 INFO - PROCESS | 1928 | Values: 69.0 67.0 69.0 68.0 68.0 66.0 67.0 67.0 69.0 85.0 07:49:51 INFO - PROCESS | 1928 | -------- Summary: end -------- 07:49:51 INFO - PROCESS | 1928 | 07:49:52 INFO - TEST-INFO | 1928: exit 0 07:49:52 INFO - TEST-OK | kraken | took 60444ms 07:49:52 INFO - SUITE-END | took 1180s 07:49:53 INFO - Completed test suite (00:19:40) 07:49:53 INFO - javascript benchmark 07:49:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.8327083645827, 3351.4302568563344, 3356.8153751543655, 3359.1434184283, 3410.795352278862, 5486.61593688441, 5621.522170615503, 5673.716314184289, 5763.135529670546, 5785.595008349417, 5601.80393686221, 5638.746256418461, 5646.632968857021, 5650.773969041234, 5652.604317697763, 5090.7200103994355, 5121.615878809085, 5132.101882170624, 5160.148575485059, 5167.6382653214105, 5272.420033796279, 5274.314339135907, 5282.603804714642, 5289.841304760844, 5291.206319052135, 4315.71947823392, 5154.974225128888, 5193.298904647881, 5205.0, 5278.762455689484, 5268.604854635918, 5275.340582427197, 5304.151335786294, 5325.1479763238085, 5345.251664766935, 5063.569596584295, 5088.033273677989, 5149.485051494858, 5214.791408343662, 5226.738663066832, 5610.635308704962, 5698.772049118031, 5728.8854222915725, 5731.168980497846, 5758.452946970031, 6172.629642221481, 6173.783917562909, 6182.041783523561, 6185.041318595625, 6187.721552530147, 5170.302009228743, 6069.969650151766, 6115.523178280689, 6178.845528861814, 6212.316645169028, 5755.913661295084, 5784.739686714108, 5788.363280039192, 5806.535477161817, 5878.147668588116, 6259.047562194929, 6289.685515724195, 6297.401746834135, 6304.747810087637, 6305.5901366411035, 5977.850553736104, 6053.909191362133, 6143.139960405547, 6151.446369826737, 6151.692415379213, 6113.2330707408255, 6126.853582554517, 6133.854967165038, 6137.468918203611, 6242.438180563771, 6183.752649894044, 6191.0, 6192.380761923771, 6198.597091189058, 6218.129461875341, 5153.974230128825, 6177.413145751237, 6178.9495785715635, 6228.065790131516, 6230.221222347207, 4783.091212669582, 4884.5603895649565, 4889.315495830587, 4894.143524883131, 4915.18899381598, 4009.6190861868076, 4319.956800432018, 4438.1567502174485, 4445.333200020023, 4460.576245256695, 5721.284839395075, 5763.740631671585, 5768.423157684282, 5774.422557744192, 5781.71091445426, 6137.171481849982, 6204.348543402878, 6261.467775239064, 6267.4986001119805, 6298.1812364392335, 5689.516391106719, 5733.88532229353, 5737.254156959652, 5738.340090889601, 5747.4252574742195, 5328.17413300939, 5337.599680024014, 5344.171653393731, 5348.759305831247, 5352.785888564492, 6219.40915613016, 6267.0286105653695, 6275.309715931153, 6282.623042617366, 6290.779822706183, 5541.501264886179, 5980.37206093354, 6179.0, 6246.406591373812, 6272.153259309935, 6354.586951848115, 6360.872782544416, 6365.586236894587, 6384.233891932998, 6393.680315984089, 6191.19514463117, 6210.161628180138, 6213.813585592439, 6221.47117495018, 6224.346443623354, 5076.873078173076, 6114.266954506099, 6129.509639228941, 6138.202033735586, 6164.0, 5924.674142922181, 5931.021381472181, 5935.792247271497, 5946.256717910262, 5967.433093856077, 5928.614640048383, 5932.7033648318275, 5937.7921772737745, 5940.584159108821, 5961.2846458425265, 5259.55293800016, 5759.683217423082, 5783.624064435799, 5800.332961709289, 5865.794697185578, 5998.790042348497, 6122.886104953835, 6134.227450118235, 6146.385361463817, 6152.354002829638, 5409.621326507264, 5479.547919801176, 5923.0, 5966.970165149147, 5976.970115149396], "value": 5640.6450027069295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.5414416799453, 482.59220958290234, 516.7519590596512, 523.1838332201766, 546.6556069676103, 24415.63376549351, 31586.368272634532, 31594.368112637763, 31614.89347872825, 31646.36707265859, 33750.493742593884, 40625.59374406255, 41423.0, 43206.0, 43214.567854321445, 9300.860487092716, 9313.906860931405, 9390.389624674373, 9421.293402994768, 9473.57368918397, 9374.953125234364, 9476.66831660892, 9487.383320084207, 9488.952555237214, 9496.002919693436, 9469.100435458655, 9495.477748723817, 9497.477638729868, 9498.572564234626, 9514.048595140464, 9394.248460123208, 9464.006279340707, 9493.66772162971, 9495.477748723852, 9509.239260859114, 9266.583003764847, 9338.95330523343, 9347.766305842408, 9349.906500934972, 9372.718818435458, 39128.0, 39435.81692549228, 39469.80265098656, 39497.81506554808, 39544.802275988724, 3200.9519857201926, 3359.277755282626, 3431.330890476358, 3643.2531331077066, 3664.2305115925687, 27906.441871162668, 27915.975124477587, 28186.859065704746, 30252.09243722692, 30365.696343036507, 3225.048610659861, 3242.238074052619, 3288.276901267427, 3329.1510664780326, 3353.02762198962, 477273.0, 506580.4735621142, 508407.0, 510102.0, 511906.0, 55879.0, 56193.0, 56235.71882140563, 56302.68065151504, 56329.71835140798, 59049.70475147683, 59140.40859591435, 59165.112523312186, 59334.51663708356, 59366.406335936954, 13962.511312104027, 15373.538793836204, 15391.538253852403, 15395.61510962235, 15415.61460963485, 11716.414179291172, 11776.352300623377, 11796.0, 11824.940875295568, 11840.230385025117, 15159.545213643609, 15211.847881521264, 15217.6195595111, 15222.847771522143, 15433.691326173413, 4961.751912404366, 6358.0, 6453.419192272718, 6551.934480655228, 6569.80290591283, 775.4998026273022, 836.8033512124646, 1043.780806030742, 1053.799778042185, 1079.4945776539741, 27123.728762712515, 28682.282942926176, 28829.42341153165, 29702.0, 29731.0, 1026.1380440430075, 1052.736815796051, 1088.7931293054294, 1117.402189828438, 1137.294877176156, 12086.637400877988, 14739.263036848115, 14966.700665986837, 15329.46346877854, 15397.461088861835, 631645.0, 638758.0, 638818.0, 638859.6399126102, 640140.0, 56865.43134568601, 56901.71549142228, 56936.153192340214, 57124.14313785297, 57133.42866571364, 1211.357980270458, 1248.3446190749928, 1306.3664122900575, 1352.60774375432, 1374.8350197976306, 28793.712062879942, 29134.0, 29930.85034574813, 30065.248368790955, 30684.539731904486, 47278.29082563833, 47690.76154619205, 47844.521554784005, 47889.0, 47911.28133077937, 399912.0, 531191.0, 533424.0, 533921.0, 534359.0, 36955.81522092372, 37398.439023414125, 38175.6182438172, 38374.23251535009, 38470.23059538737, 44928.775356124315, 45115.323270150315, 45569.31646025379, 46139.0, 46440.53559464362, 30199.997000795247, 31410.0, 37161.814190928875, 37619.435708464945, 38909.80545097256, 36753.448698270084, 36823.26353472969, 37614.811925940194, 37844.432333514465, 38003.239935201695, 34595.4810677835, 34671.95984120481, 34955.47566786449, 35299.470507941885, 35781.28437431289, 31528.684713152576, 32775.67224327822, 32999.0, 33055.00834974954, 33259.50110748292, 24059.879700602087, 24167.15414960468, 24461.633075503527, 25101.623475647513, 25135.371615709755], "value": 19202.51116693077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6457284424199, 358.7972795370615, 361.58417819507565, 368.756620630384, 391.0497491096634, 14333.713325733479, 14601.269936503186, 15416.0, 16059.839401605981, 19983.800161998377, 18403.90798046008, 19407.90296048518, 20091.397258082245, 20413.183472661076, 20436.693449598268, 5662.886742265152, 6043.758249670008, 6054.5459090568165, 6057.848553786146, 6059.757609695629, 4146.1914926589325, 4638.582527572517, 5911.113333000041, 5972.074328479092, 6042.909356359658, 4381.824727010931, 4422.911541769163, 6294.2761582417925, 6648.601083934956, 6661.533692641517, 6345.365463453664, 6548.214214294268, 6618.900716489208, 6692.63190524521, 6731.427828634596, 5723.713814309268, 5728.598998070137, 5731.684757338345, 5739.483446489836, 5750.769969201227, 24279.878600606884, 25225.0, 25301.873490632614, 25309.367265818317, 25589.848456819487, 2207.293666026872, 2329.9650505242435, 2368.9644655330185, 2716.994711956569, 3067.1105379439937, 14928.552143435714, 15034.69930601382, 15597.610059748484, 15619.453319133776, 15669.373225071098, 1809.0, 1828.1042289278291, 1861.8603604729565, 1869.8130186981193, 1994.670879304913, 68207.31792682059, 103875.0, 105768.0, 107681.0, 111906.44046779795, 10278.691639250834, 10512.26414151002, 10681.519331630096, 10767.061928852881, 10892.346459212473, 13423.865761342457, 13428.530001450097, 13447.125936814076, 13461.730765384638, 13477.797833032513, 6865.0, 7979.241972012649, 8031.558264295403, 8090.757277281691, 8452.915470845335, 6845.261895241943, 8163.469374490639, 8234.835303294021, 8385.454945428528, 8403.579821008925, 6451.516136289703, 6555.836104097436, 6563.343665633399, 6592.110065141241, 6607.0, 1645.7449095390234, 1770.371518111052, 1806.927722891096, 1818.1091265280181, 1825.9360922367653, 810.6433169405443, 846.386369881843, 873.6811063961608, 909.658254075247, 960.5869476125333, 16212.594685132966, 20327.491872174993, 22206.555868882533, 22268.888655556944, 22273.443163920707, 916.2944532709777, 929.4423345992485, 940.4451373689419, 970.2189737261431, 975.2198241406988, 11408.201425900108, 11630.476628551736, 11661.766764664659, 11666.591669291534, 11679.532818687327, 532043.0, 537423.0, 553963.0, 571525.0, 572490.0, 59717.40282597205, 62286.44283892939, 70794.0, 99507.0, 101373.49313253387, 927.2535608834953, 936.5644975086617, 953.952302384878, 962.2879069488528, 1009.1422291052546, 15915.204239787732, 17889.642207156045, 18540.16569254414, 18547.81452185461, 19056.714149287494, 45076.32385514286, 45366.0, 45901.31148032715, 46040.0, 46770.76614617041, 62949.0, 63829.68085159545, 64063.7187256243, 64580.0, 66020.33979660142, 7056.506044577036, 7389.261073892782, 7744.535327880345, 8116.350691944748, 8432.494050356998, 13129.671758205739, 13145.145565538209, 13287.269200193696, 13409.463621455032, 13579.864201358256, 11284.32294062359, 11481.36852473122, 11555.2489088209, 11629.127815414047, 11810.40947952616, 10395.94802025985, 11023.503942322775, 11136.276142050741, 11358.318500889973, 11495.655130346104, 11474.311541307548, 11525.494629171253, 11648.184627076362, 11688.064954803425, 11840.0, 7945.682172713026, 7974.84050319002, 8278.586070696321, 8373.288270497076, 8387.538685372363, 10087.445190514592, 10708.196885233794, 11010.834837477283, 11380.54477820878, 11667.42489763871], "value": 9362.103545474714, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.5633310007, 1497.595649174723, 1654.7104256755065, 1741.1555395633109, 1829.7438358629795, 7974.920250797491, 8406.66373345067, 8421.326293896489, 8441.155884411555, 8447.23974842264, 7332.19345871955, 7584.620768961558, 7589.0, 7656.846863062749, 8295.958520207405, 1735.297204632126, 2050.3643870400224, 2067.762207346152, 2087.9373618791383, 2276.9658455123185, 2299.7240331160283, 2310.364649721323, 2314.490812021361, 2314.9884250578725, 2315.4327189838427, 1873.2319748902953, 2224.399412158716, 2262.9207977720785, 2283.7145356830397, 2287.3938406322336, 2247.325802259316, 2250.1211488407034, 2272.1820144747876, 2272.863628182314, 2277.7836105569945, 2275.7382900966436, 2303.884805759705, 2305.1586171047616, 2306.411864974438, 2307.792298693109, 8239.588020599005, 8260.586970651504, 8379.994400671898, 8381.203785640355, 8396.160383961616, 4967.453580106186, 5142.897142057138, 5143.871403214912, 5147.22791581262, 5166.870828229286, 3262.8531716072835, 3369.730421566269, 3427.640097789721, 3609.4946707460977, 3630.110622897381, 817.5952903312839, 1040.9017841195512, 1070.9410982395966, 1078.1267173589417, 1085.484394912418, 10636.62771802991, 10705.785884282272, 10729.785404291948, 10742.570297188104, 10744.516496757664, 4382.583654552876, 4398.318260669601, 4483.730976141442, 4495.752733599618, 4606.332081848113, 9409.247260219166, 9422.858657120149, 9435.245180385553, 9459.148676619137, 9460.0, 5048.646594738333, 5133.717645529457, 5143.3827940646615, 5156.690598564098, 5159.277701121846, 4668.626509879202, 4672.789724462407, 4699.201135806853, 4718.764061796896, 4729.763511824395, 3342.1644588852787, 3387.085486918518, 3399.2861499084975, 3446.965530344715, 3508.456189290664, 1072.5602502973743, 1123.7471568896933, 1220.4690959432676, 1239.2192918461492, 1240.5472002719123, 864.3981615337752, 877.6966205185295, 880.4673172730534, 899.6266549382065, 916.1113719691888, 5417.972910135503, 5457.103231611226, 5632.690202038927, 5707.914381284201, 5718.25662663851, 875.65411662393, 888.7733627924838, 929.7722058095675, 936.3773090894615, 961.2838435365558, 3527.470879368115, 3724.255148970217, 3861.0, 3893.3381325174787, 3895.35726605107, 9638.42169469834, 9855.01449855023, 9917.851232231522, 10081.747956301151, 10082.69751907444, 9358.953205233931, 9865.408075515348, 10027.448490333101, 10036.849447258297, 10056.949715251378, 7483.32650061497, 7625.923740762521, 7627.4279429043045, 7665.540067595962, 7754.961225193725, 5557.833265002057, 5591.552675786009, 5637.661740295595, 5826.213461182686, 5897.498712609305, 9246.445213287225, 9350.485723285285, 9373.062693730844, 9380.718578442658, 9390.436573805593, 9233.122853328923, 9334.879814422313, 9445.669401570913, 9456.101670341306, 9522.0954009369, 6726.260111387841, 6947.513674042769, 7045.753398631024, 7061.6822243000215, 7219.386352160125, 7093.432525398059, 7761.495502792301, 7864.2922137007945, 7868.055833299812, 7869.803254918444, 6529.836754081186, 7030.543014704028, 7109.964450177716, 7180.138383393817, 7193.172785129568, 6878.793636190922, 6958.547694399847, 6965.756198533027, 6967.75612853568, 7020.262872398324, 6528.706208220547, 6685.2646208916, 6688.197416310074, 6688.765893193715, 6722.798316050526, 4047.4333593296374, 4514.25514790056, 4649.372334734768, 4676.719396836201, 4709.199436095941, 4730.266808644666, 4755.785989630405, 4817.229243321051, 4885.584725298248, 5040.798368065236], "value": 4343.962436616519, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.71743223737775, 290.1440749788125, 292.76871271695364, 300.4276852595804, 305.18515563445595, 5000.39995200576, 5129.871753206167, 5162.690238585678, 5182.714950677711, 5195.090859099661, 4248.957510424895, 4838.588719958808, 4910.386201724785, 5026.472220416849, 5062.645614806965, 3431.1593659553446, 3894.3963685628773, 3919.6668283195963, 3948.1708841143395, 3983.6813054955533, 3912.4522566840665, 3927.607239276064, 3942.7042971777087, 3953.604639536052, 3981.4226937094104, 3792.5259342582176, 3906.3554513505237, 3916.3929590913453, 3925.9803700981456, 3943.5662077171633, 3879.612038796126, 3913.66733827625, 3915.3931140672958, 3919.529656441245, 3936.350502167139, 3407.8807241746667, 3428.862845486178, 3431.725461963037, 3431.9142021449416, 3435.862565497377, 4420.215264383418, 4649.1399091167905, 4935.185694360463, 4982.832467876486, 5007.624428167902, 4229.344451610005, 4411.250087485135, 4458.9554104459185, 4498.550144985508, 4511.526289739563, 4461.888452788674, 5321.148616221425, 5327.413984461706, 5395.843431592523, 5407.188921661743, 3712.777233366007, 3721.93432644882, 3736.2527494500937, 3740.233252183296, 3743.700503959704, 4870.488598697121, 5349.839504814861, 5489.945100548984, 5558.77764889408, 5560.304961879725, 3300.504924261332, 3429.8628054877527, 3831.8084095795098, 3850.268448994682, 3853.460515527828, 3427.8286085695618, 3674.797886116289, 3862.0, 3915.8477425672036, 3916.2559113768293, 3700.6114357992024, 4410.919324765453, 4499.280115181556, 4500.17485137637, 4519.435070616173, 4951.356323677899, 5148.0, 5315.176147697113, 5447.4824891635235, 5487.670739755627, 1025.610268098118, 1058.8888166742536, 1219.1283232488813, 1220.6460126563197, 1222.468226321553, 779.5400713578903, 790.3005839831791, 798.7124635131348, 812.3095368936475, 845.5222799118478, 3917.7061720371084, 4779.665423420397, 4780.49804770501, 4788.257820037899, 4805.951940480621, 4711.693739906964, 5192.688438693691, 5244.816431424882, 5246.976839516257, 5319.946800532022, 3681.6686498214754, 3755.549334079928, 4750.786214620351, 4785.282207668808, 4795.808167673254, 2289.6794448777187, 2333.1484008337175, 2744.7118052604587, 2747.3543717226435, 2747.7880306353577, 4941.950580494221, 5327.987682340343, 5447.0, 5452.509274165344, 5468.097763868917, 3811.7331786774657, 4376.6498680105515, 4444.244613301755, 4991.7304165771475, 5222.92165617516, 4588.655850811202, 4667.953320466752, 5348.823258882961, 5380.7309634518115, 5443.727813609304, 4051.331530297468, 4785.353977213171, 5171.81898633546, 5186.014657215117, 5196.272521846869, 4908.410990681141, 5150.690958542499, 5167.870803229799, 5555.694436806015, 5622.24099746529, 5036.269740887477, 5728.312602487728, 5750.25246717924, 5765.8558536036935, 5786.42135786418, 3743.2513497300106, 3775.0, 3786.242751449666, 3790.374588192917, 3813.1801662642665, 4533.750643714628, 4840.540148685869, 5582.218489411404, 5650.773969041356, 5674.0, 4639.960825659198, 4730.503297153749, 5387.81142660005, 5396.298481197419, 5422.15956526739, 4874.878128046827, 5293.735313234246, 5396.595255355943, 5413.945860541344, 5419.620626556103, 4264.232438161161, 5316.043112239679, 5328.973355133199, 5345.4654534546235, 5404.83785486436, 4574.8791546071425, 5090.949090509195, 5151.871203219949, 5346.866328341823, 5405.594580406407, 4535.79588918506, 4981.455748717909, 5277.8416647500635, 5284.894302114013, 5288.762005709829], "value": 3853.309120442684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.9029974604571, 704.5350068954488, 733.809209605503, 744.1888341707541, 756.5422919133922, 13718.245496497688, 14443.277836108178, 14483.637909052279, 14501.637459063528, 14514.782278265835, 10963.342199468038, 12464.252144871314, 12474.251544907289, 12482.687932801684, 12485.25088494691, 647.3688154049787, 668.612204921147, 797.4417907464768, 806.8466991271669, 823.6005537314397, 817.7424111404888, 821.7247222180577, 827.1604321613537, 828.714093637697, 828.7223780033696, 877.0615441477621, 877.4603618774472, 879.2921698033081, 880.1462581296132, 880.5333173418098, 681.7784220128428, 705.5202462325617, 709.6522703875115, 874.8600223964168, 875.6978842299396, 523.0950455711602, 523.1498814426557, 631.7251995381978, 650.7787352300194, 654.1136759690646, 13636.590902272848, 13670.248136352497, 13686.31568421575, 13690.178589284675, 13698.38357273925, 12269.386530673431, 12437.689057773538, 12558.257774764861, 12642.683932901658, 12893.161944473579, 11658.766824663458, 11727.354995475245, 11820.29078255299, 11837.940810295893, 12160.0, 4258.723182993126, 4261.893452663678, 4364.978175109104, 4443.533428989942, 4443.888902777424, 51088.74455627735, 73833.14002319702, 74105.6294718523, 77584.22415775826, 79634.60182699049, 10055.346402483816, 10100.949495252476, 10107.39355638664, 10248.948755256177, 10294.124999374988, 19063.332783352795, 19069.237230510902, 19074.618507630046, 19121.808781912, 19192.520186995156, 7049.823754406181, 7174.246704096099, 7189.065421495172, 7259.673314700851, 7418.591977441185, 3983.462232598562, 3990.900227494336, 4142.979285103555, 4145.129522800185, 4165.958340416557, 3070.201747545654, 3107.2697915989725, 3159.4786860168274, 3171.3815805917625, 3182.6180858297153, 527.380328114464, 616.0851136062943, 616.4236438929615, 619.4579742725116, 636.1539152926597, 537.5994883811585, 584.9385814489502, 595.8838026584862, 597.9910301345483, 597.9970100149471, 8432.0, 8466.365022623328, 8474.957625211835, 8487.787805304917, 8761.211490965843, 489.9216125419916, 547.4771593128633, 550.8925759476944, 571.3401021819722, 575.6920047774421, 2824.7457728804097, 3368.360011597789, 3925.8429662813737, 3997.300472417315, 4166.791660416967, 72949.90575141377, 78681.60659196667, 104546.0, 108624.0, 117292.0, 86335.0, 86896.0, 88312.0, 89826.65260021105, 91160.0, 50374.236901708995, 68985.65507172432, 85321.57339213388, 86092.56953715191, 89407.55296223608, 6410.839729006812, 7697.0378702662165, 7810.0, 7920.247576480225, 8080.595970201584, 39062.80468597753, 54264.0, 55341.33975980727, 56516.0, 56562.71718641381, 59260.0, 77062.2293777055, 78838.0, 81488.59255703684, 83409.58295208485, 10622.8937710625, 10661.307015043998, 10966.396848173426, 10983.39591322485, 11222.270552414067, 9847.60609575609, 11430.885691143314, 11694.415279236175, 11770.529178833094, 11896.107791915469, 11009.25676282923, 11058.502367393326, 11201.943990279997, 11203.21577489568, 11376.0, 9481.754368496982, 12608.369581520703, 12626.684332891751, 12903.483860645469, 13035.0, 10982.725431864268, 12564.246145231315, 12658.493660253487, 12662.683432914251, 13310.467581297027, 8875.689350872688, 10511.632092876713, 10971.177161713063, 10993.61522346714, 11077.279976801483, 10716.410597417216, 10752.0, 10856.294340867818, 11008.0, 11055.72360690989], "value": 6806.116390999823, "unit": "score"}], "name": "dromaeo_css", "value": 6978.91812155442, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 112.0, 114.0, 113.0, 113.0, 120.0, 164.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 114.0, 150.0, 115.0, 113.0, 113.0, 111.0, 148.0, 111.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 180.0, 185.0, 185.0, 181.0, 192.0, 182.0, 182.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 59.0, 60.0, 89.0, 63.0, 62.0, 59.0, 59.0, 82.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 92.0, 71.0, 70.0, 69.0, 90.0, 68.0, 69.0, 93.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 88.0, 90.0, 87.0, 91.0, 87.0, 90.0, 87.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 162.0, 122.0, 98.0, 98.0, 97.0, 99.0, 98.0, 97.0, 97.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 85.0, 89.0, 85.0, 87.0, 86.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 53.0, 55.0, 55.0, 57.0, 55.0, 54.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 71.0, 76.0, 71.0, 70.0, 71.0, 71.0, 70.0, 73.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 87.0, 86.0, 91.0, 87.0, 87.0, 90.0, 88.0, 93.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 137.0, 153.0, 166.0, 158.0, 140.0, 150.0, 166.0, 212.0, 153.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 175.0, 175.0, 176.0, 189.0, 234.0, 200.0, 175.0, 174.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 68.0, 68.0, 66.0, 67.0, 67.0, 69.0, 85.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1483.6000000000001, "alertThreshold": 2.0}]} 07:49:53 INFO - Return code: 0 07:49:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:49:53 INFO - # TBPL SUCCESS # 07:49:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:49:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:49:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:49:53 INFO - 2016-11-15 07:49:53.557 screenresolution[1932:16783] starting screenresolution argv=screenresolution get 07:49:53 INFO - 2016-11-15 07:49:53.560 screenresolution[1932:16783] Display 0: 1600x1200x32@60 07:49:53 INFO - 2016-11-15 07:49:53.566 screenresolution[1933:16786] starting screenresolution argv=screenresolution list 07:49:53 INFO - Available Modes on Display 0 07:49:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:49:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:49:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:49:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:49:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:49:53 INFO - Intel Iris: 07:49:53 INFO - Chipset Model: Intel Iris 07:49:53 INFO - Type: GPU 07:49:53 INFO - Bus: Built-In 07:49:53 INFO - VRAM (Dynamic, Max): 1536 MB 07:49:53 INFO - Vendor: Intel (0x8086) 07:49:53 INFO - Device ID: 0x0a2e 07:49:53 INFO - Revision ID: 0x0009 07:49:53 INFO - Displays: 07:49:53 INFO - Display: 07:49:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:49:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:49:53 INFO - Display Serial Number: 1600x1200 60 07:49:53 INFO - Main Display: Yes 07:49:53 INFO - Mirror: Off 07:49:53 INFO - Online: Yes 07:49:53 INFO - Rotation: Supported 07:49:53 INFO - Return code: 0 07:49:53 INFO - Running post-action listener: _resource_record_post_action 07:49:53 INFO - [mozharness: 2016-11-15 15:49:53.803784Z] Finished run-tests step (success) 07:49:53 INFO - Running post-run listener: _resource_record_post_run 07:49:53 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 655360; Write bytes: 699473920; Read time: 9; Write time: 1996 07:49:53 INFO - TinderboxPrint: CPU usage
53.6% 07:49:53 INFO - TinderboxPrint: I/O read bytes / time
655,360 / 9 07:49:53 INFO - TinderboxPrint: I/O write bytes / time
699,473,920 / 1,996 07:49:53 INFO - TinderboxPrint: CPU idle
2,257.8 (46.4%) 07:49:53 INFO - TinderboxPrint: CPU system
978.4 (20.1%) 07:49:53 INFO - TinderboxPrint: CPU user
1,625.4 (33.4%) 07:49:53 INFO - TinderboxPrint: Swap in / out
399,589,376 / 0 07:49:53 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 391801344; Write bytes: 384587776; Read time: 29549; Write time: 830 07:49:53 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 532480; Write bytes: 297605120; Read time: 4; Write time: 1118 07:49:54 INFO - Running post-run listener: _upload_blobber_files 07:49:54 INFO - Blob upload gear active. 07:49:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:49:54 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 07:49:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:49:54 INFO - (blobuploader) - INFO - Open directory for files ... 07:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:56 INFO - (blobuploader) - INFO - Done attempting. 07:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:57 INFO - (blobuploader) - INFO - Done attempting. 07:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:59 INFO - (blobuploader) - INFO - Done attempting. 07:49:59 INFO - (blobuploader) - INFO - Iteration through files over. 07:49:59 INFO - Return code: 0 07:49:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:49:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:49:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cca690236ffb88c392006ca4676561d1110c69f94afdb3f62ec132799ce5bef8e4b5427851dad9242f468b881163646277673f34bc36b5c62a253c06ccc7f38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe1372e048a5ae66543f728367831868024954aaca60bbf8a82d351813a722754358c4de946bea73079d0d188026a0f62a87836684cb1fceb74498c730901e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d229db50f6af6870f2009be866b1d04d6df8545598c42c72d4b3a990f471b2acbb64658e985ec4d4666e08d22a017c33b587862b01cc0b042752d02b8ac29732"} 07:49:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:49:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:49:59 INFO - Contents: 07:49:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cca690236ffb88c392006ca4676561d1110c69f94afdb3f62ec132799ce5bef8e4b5427851dad9242f468b881163646277673f34bc36b5c62a253c06ccc7f38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe1372e048a5ae66543f728367831868024954aaca60bbf8a82d351813a722754358c4de946bea73079d0d188026a0f62a87836684cb1fceb74498c730901e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d229db50f6af6870f2009be866b1d04d6df8545598c42c72d4b3a990f471b2acbb64658e985ec4d4666e08d22a017c33b587862b01cc0b042752d02b8ac29732"} 07:49:59 INFO - Running post-run listener: copy_logs_to_upload_dir 07:49:59 INFO - Copying logs to upload dir... 07:49:59 INFO - mkdir: /builds/slave/test/build/upload/logs 07:49:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.758736 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 07:50:00.005167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 07:50:00.008209) ========= 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.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cca690236ffb88c392006ca4676561d1110c69f94afdb3f62ec132799ce5bef8e4b5427851dad9242f468b881163646277673f34bc36b5c62a253c06ccc7f38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe1372e048a5ae66543f728367831868024954aaca60bbf8a82d351813a722754358c4de946bea73079d0d188026a0f62a87836684cb1fceb74498c730901e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d229db50f6af6870f2009be866b1d04d6df8545598c42c72d4b3a990f471b2acbb64658e985ec4d4666e08d22a017c33b587862b01cc0b042752d02b8ac29732"} build_url:https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011983 build_url: 'https://queue.taskcluster.net/v1/task/eJ7gJyBYTcehzcCYCi47ww/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cca690236ffb88c392006ca4676561d1110c69f94afdb3f62ec132799ce5bef8e4b5427851dad9242f468b881163646277673f34bc36b5c62a253c06ccc7f38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe1372e048a5ae66543f728367831868024954aaca60bbf8a82d351813a722754358c4de946bea73079d0d188026a0f62a87836684cb1fceb74498c730901e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d229db50f6af6870f2009be866b1d04d6df8545598c42c72d4b3a990f471b2acbb64658e985ec4d4666e08d22a017c33b587862b01cc0b042752d02b8ac29732"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 07:50:00.041862) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:50:00.042150) ========= 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.zOOQPxFmri/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.ArIM9InbaA/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 07:50:00.090476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 07:50:00.090770) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 07:50:00.091085) ========= ========= Total master_lag: 0.18 =========