builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1476526784.41 results: success (0) buildid: 20161015021535 builduid: be01254197ab4426a6fbc3e182685c68 revision: 44b01519b5668699f2c1a10d362445be59711d35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:44.411691) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:44.412298) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:44.412615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:44.436635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:44.436967) ========= 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.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-15 03:19:44-- 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% 159M=0s 2016-10-15 03:19:45 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.579244 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:45.046444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:45.046834) ========= 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.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.019191 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:45.096057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 03:19:45.096453) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 44b01519b5668699f2c1a10d362445be59711d35 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 44b01519b5668699f2c1a10d362445be59711d35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-15 03:19:45,168 truncating revision to first 12 chars 2016-10-15 03:19:45,168 Setting DEBUG logging. 2016-10-15 03:19:45,168 attempt 1/10 2016-10-15 03:19:45,168 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/44b01519b566?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-15 03:19:46,841 unpacking tar archive at: fx-team-44b01519b566/testing/mozharness/ program finished with exit code 0 elapsedTime=1.931447 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 03:19:47.049749) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:47.050063) ========= script_repo_revision: 44b01519b5668699f2c1a10d362445be59711d35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:47.050438) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:47.050700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 03:19:47.068762) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-15 03:19:47.069170) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:19:47 INFO - MultiFileLogger online at 20161015 03:19:47 in /builds/slave/test 03:19:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:19:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:19:47 INFO - {'append_to_log': False, 03:19:47 INFO - 'base_work_dir': '/builds/slave/test', 03:19:47 INFO - 'blob_upload_branch': 'Fx-Team', 03:19:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:19:47 INFO - 'branch': 'Fx-Team', 03:19:47 INFO - 'buildbot_json_path': 'buildprops.json', 03:19:47 INFO - 'config_files': ('talos/mac_config.py',), 03:19:47 INFO - 'default_actions': ('clobber', 03:19:47 INFO - 'read-buildbot-config', 03:19:47 INFO - 'download-and-extract', 03:19:47 INFO - 'populate-webroot', 03:19:47 INFO - 'create-virtualenv', 03:19:47 INFO - 'install', 03:19:47 INFO - 'run-tests'), 03:19:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:19:47 INFO - 'download_minidump_stackwalk': True, 03:19:47 INFO - 'download_symbols': 'ondemand', 03:19:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:19:47 INFO - 'tooltool.py': '/tools/tooltool.py', 03:19:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:19:47 INFO - '/tools/misc-python/virtualenv.py')}, 03:19:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:19:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:19:47 INFO - 'installer_path': 'installer.exe', 03:19:47 INFO - 'log_level': 'info', 03:19:47 INFO - 'log_name': 'talos', 03:19:47 INFO - 'log_to_console': True, 03:19:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:19:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:19:47 INFO - 'opt_config_files': (), 03:19:47 INFO - 'pip_index': False, 03:19:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:19:47 INFO - 'cmd': ('bash', 03:19:47 INFO - '-c', 03:19:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:19:47 INFO - 'enabled': True, 03:19:47 INFO - 'halt_on_failure': False, 03:19:47 INFO - 'name': 'check_screen_resolution'},), 03:19:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:19:47 INFO - 'cmd': ('bash', 03:19:47 INFO - '-c', 03:19:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:19:47 INFO - 'enabled': True, 03:19:47 INFO - 'halt_on_failure': False, 03:19:47 INFO - 'name': 'check_screen_resolution'},), 03:19:47 INFO - 'run_cmd_checks_enabled': True, 03:19:47 INFO - 'sps_profile': False, 03:19:47 INFO - 'sps_profile_interval': 0, 03:19:47 INFO - 'suite': 'dromaeojs', 03:19:47 INFO - 'system_bits': '32', 03:19:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:19:47 INFO - 'title': 't-yosemite-r7-0027', 03:19:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:19:47 INFO - 'use_talos_json': True, 03:19:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:19:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:19:47 INFO - 'work_dir': 'build'} 03:19:47 INFO - [mozharness: 2016-10-15 10:19:47.270307Z] Running clobber step. 03:19:47 INFO - Running pre-action listener: _resource_record_pre_action 03:19:47 INFO - Running main action method: clobber 03:19:47 INFO - rmtree: /builds/slave/test/build 03:19:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:19:49 INFO - Running post-action listener: _resource_record_post_action 03:19:49 INFO - [mozharness: 2016-10-15 10:19:49.246226Z] Finished clobber step (success) 03:19:49 INFO - [mozharness: 2016-10-15 10:19:49.246360Z] Running read-buildbot-config step. 03:19:49 INFO - Running pre-action listener: _resource_record_pre_action 03:19:49 INFO - Running main action method: read_buildbot_config 03:19:49 INFO - Using buildbot properties: 03:19:49 INFO - { 03:19:49 INFO - "project": "", 03:19:49 INFO - "product": "firefox", 03:19:49 INFO - "script_repo_revision": "production", 03:19:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:19:49 INFO - "repository": "", 03:19:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:19:49 INFO - "buildid": "20161015021535", 03:19:49 INFO - "pgo_build": "False", 03:19:49 INFO - "basedir": "/builds/slave/test", 03:19:49 INFO - "buildnumber": 18, 03:19:49 INFO - "slavename": "t-yosemite-r7-0027", 03:19:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:19:49 INFO - "platform": "yosemite_r7", 03:19:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:19:49 INFO - "branch": "fx-team", 03:19:49 INFO - "repo_path": "integration/fx-team", 03:19:49 INFO - "revision": "44b01519b5668699f2c1a10d362445be59711d35", 03:19:49 INFO - "stage_platform": "macosx64", 03:19:49 INFO - "builduid": "be01254197ab4426a6fbc3e182685c68", 03:19:49 INFO - "slavebuilddir": "test" 03:19:49 INFO - } 03:19:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 03:19:49 INFO - Running post-action listener: _resource_record_post_action 03:19:49 INFO - [mozharness: 2016-10-15 10:19:49.249575Z] Finished read-buildbot-config step (success) 03:19:49 INFO - [mozharness: 2016-10-15 10:19:49.249679Z] Running download-and-extract step. 03:19:49 INFO - Running pre-action listener: _resource_record_pre_action 03:19:49 INFO - Running main action method: download_and_extract 03:19:49 INFO - mkdir: /builds/slave/test/build/tests 03:19:49 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')]} 03:19:49 INFO - trying https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 03:19:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:19:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 03:19:50 INFO - Downloaded 1199 bytes. 03:19:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:19:50 INFO - Using the following test package requirements: 03:19:50 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:19:50 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 03:19:50 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 03:19:50 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'jsshell-mac.zip'], 03:19:50 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 03:19:50 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:19:50 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 03:19:50 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 03:19:50 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 03:19:50 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:19:50 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 03:19:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:19:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:19:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:19:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:19:50 INFO - Http code: 200 03:19:50 INFO - via: 1.1 dc7c4fb5024ff022cad1642ec506a6e8.cloudfront.net (CloudFront) 03:19:50 INFO - x-amz-cf-id: vdJML9qINhwRdbwURczhh4Cvujw37yavFMkpENhwmZcEdI4GSkYi0g== 03:19:50 INFO - x-amz-version-id: RBeVOx8bHHwskbCM6ouHAs_MO5.PpXTz 03:19:51 INFO - Expected file size: 16516732 03:19:51 INFO - Obtained file size: 16516732 03:19:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:19:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:19:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:19:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:19:58 INFO - Http code: 200 03:19:58 INFO - via: 1.1 75d3bdff1b8eb2920f04077d77f16ca0.cloudfront.net (CloudFront) 03:19:58 INFO - x-amz-cf-id: Rz4yLZqMGAmcbj1KbFKLd0WW9qLEBuhDQAyV2vT06-r1DliwPq8q2w== 03:19:58 INFO - x-amz-version-id: RBeVOx8bHHwskbCM6ouHAs_MO5.PpXTz 03:19:58 INFO - Expected file size: 16516732 03:19:58 INFO - Obtained file size: 16516732 03:20:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 03:20:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:20:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 03:20:05 INFO - Http code: 200 03:20:05 INFO - via: 1.1 2a47832c458ab2a6b20c8363f5aa35ea.cloudfront.net (CloudFront) 03:20:05 INFO - x-amz-cf-id: Ba0MQdh2qkSnNvMPrdIZ8maSOmSPoF-tlAc8_AcTWy_QJ38cykoBdQ== 03:20:05 INFO - x-amz-version-id: hSeM5f.tbpJaiZYn4UdhJhMrEUKUYQoc 03:20:05 INFO - Expected file size: 14409028 03:20:05 INFO - Obtained file size: 14409028 03:20:06 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')]} 03:20:06 INFO - trying https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:20:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 03:20:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 03:20:09 INFO - Downloaded 111958631 bytes. 03:20:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:20:09 INFO - mkdir: /builds/slave/test/properties 03:20:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:20:09 INFO - Writing to file /builds/slave/test/properties/build_url 03:20:09 INFO - Contents: 03:20:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:20:10 INFO - Running post-action listener: _resource_record_post_action 03:20:10 INFO - Running post-action listener: set_extra_try_arguments 03:20:10 INFO - [mozharness: 2016-10-15 10:20:10.108077Z] Finished download-and-extract step (success) 03:20:10 INFO - [mozharness: 2016-10-15 10:20:10.108229Z] Running populate-webroot step. 03:20:10 INFO - Running pre-action listener: _resource_record_pre_action 03:20:10 INFO - Running main action method: populate_webroot 03:20:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:20:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:20:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:20:10 INFO - '--tppagecycles', 03:20:10 INFO - '7'], 03:20:10 INFO - 'tests': ['tsvgm']}}, 03:20:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:20:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:20:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:20:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:20:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['damp', 'tps']}, 03:20:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'tests': ['damp', 'tps']}, 03:20:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['dromaeo_dom']}, 03:20:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:20:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['basic_compositor_video']}, 03:20:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:20:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['a11yr', 03:20:10 INFO - 'ts_paint', 03:20:10 INFO - 'tpaint', 03:20:10 INFO - 'sessionrestore', 03:20:10 INFO - 'sessionrestore_no_auto_restore', 03:20:10 INFO - 'tabpaint']}, 03:20:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['tsvgx', 03:20:10 INFO - 'tsvgr_opacity', 03:20:10 INFO - 'tart', 03:20:10 INFO - 'tscrollx', 03:20:10 INFO - 'cart']}, 03:20:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:20:10 INFO - 'tsvgr_opacity', 03:20:10 INFO - 'tart', 03:20:10 INFO - 'tscrollx', 03:20:10 INFO - 'cart']}, 03:20:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'talos_options': ['--disable-e10s'], 03:20:10 INFO - 'tests': ['tp5o']}, 03:20:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'tests': ['tp5o']}, 03:20:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'talos_options': ['--disable-e10s', 03:20:10 INFO - '--xperf_path', 03:20:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:10 INFO - 'tests': ['tp5n']}, 03:20:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:10 INFO - 'talos_options': ['--xperf_path', 03:20:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:10 INFO - 'tests': ['tp5n']}}, 03:20:10 INFO - 'talos.zip': {'path': '', 03:20:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:20:10 INFO - Running post-action listener: _resource_record_post_action 03:20:10 INFO - [mozharness: 2016-10-15 10:20:10.127212Z] Finished populate-webroot step (success) 03:20:10 INFO - [mozharness: 2016-10-15 10:20:10.127320Z] Running create-virtualenv step. 03:20:10 INFO - Running pre-action listener: _resource_record_pre_action 03:20:10 INFO - Running main action method: create_virtualenv 03:20:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:20:10 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 03:20:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:20:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:20:10 INFO - Using real prefix '/tools/python27' 03:20:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:20:10 INFO - Installing distribute.............................................................................................................................................................................................done. 03:20:13 INFO - Installing pip.................done. 03:20:13 INFO - Return code: 0 03:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:20:14 INFO - Reading from file tmpfile_stdout 03:20:14 INFO - Output received: 03:20:14 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) 03:20:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:20: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')]} 03:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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')]} 03:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20: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', 'pip>=1.5'] in /builds/slave/test/build 03:20: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 pip>=1.5 03:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:14 INFO - 'HOME': '/Users/cltbld', 03:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:14 INFO - 'LOGNAME': 'cltbld', 03:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:14 INFO - 'MOZ_NO_REMOTE': '1', 03:20:14 INFO - 'NO_EM_RESTART': '1', 03:20:14 INFO - 'PAGER': '/bin/cat', 03:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:14 INFO - 'PWD': '/builds/slave/test', 03:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:14 INFO - 'SHELL': '/bin/bash', 03:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:14 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:14 INFO - 'USER': 'cltbld', 03:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:14 INFO - 'XPC_FLAGS': '0x0', 03:20:14 INFO - 'XPC_SERVICE_NAME': '0', 03:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:14 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 03:20:14 INFO - Cleaning up... 03:20:14 INFO - Return code: 0 03:20:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:20: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')]} 03:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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')]} 03:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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', '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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:20: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 psutil>=3.1.1 03:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:14 INFO - 'HOME': '/Users/cltbld', 03:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:14 INFO - 'LOGNAME': 'cltbld', 03:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:14 INFO - 'MOZ_NO_REMOTE': '1', 03:20:14 INFO - 'NO_EM_RESTART': '1', 03:20:14 INFO - 'PAGER': '/bin/cat', 03:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:14 INFO - 'PWD': '/builds/slave/test', 03:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:14 INFO - 'SHELL': '/bin/bash', 03:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:14 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:14 INFO - 'USER': 'cltbld', 03:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:14 INFO - 'XPC_FLAGS': '0x0', 03:20:14 INFO - 'XPC_SERVICE_NAME': '0', 03:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:14 INFO - Downloading/unpacking psutil>=3.1.1 03:20: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 03:20: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 03:20: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 03:20: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 03:20: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 03:20: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 03:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:20:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:20:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:20:18 INFO - Installing collected packages: psutil 03:20:18 INFO - Running setup.py install for psutil 03:20:18 INFO - building 'psutil._psutil_osx' extension 03:20:18 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 03:20:18 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 03:20:18 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 03:20:18 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 03:20:18 INFO - building 'psutil._psutil_posix' extension 03:20:18 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 03:20:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:20:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:20:18 INFO - ^ 03:20:18 INFO - 1 warning generated. 03:20:18 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 03:20:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:20:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:20:19 INFO - Successfully installed psutil 03:20:19 INFO - Cleaning up... 03:20:19 INFO - Return code: 0 03:20:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:19 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:19 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 03:20:19 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 03:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:19 INFO - 'HOME': '/Users/cltbld', 03:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:19 INFO - 'LOGNAME': 'cltbld', 03:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:19 INFO - 'MOZ_NO_REMOTE': '1', 03:20:19 INFO - 'NO_EM_RESTART': '1', 03:20:19 INFO - 'PAGER': '/bin/cat', 03:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:19 INFO - 'PWD': '/builds/slave/test', 03:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:19 INFO - 'SHELL': '/bin/bash', 03:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:19 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:19 INFO - 'USER': 'cltbld', 03:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:19 INFO - 'XPC_FLAGS': '0x0', 03:20:19 INFO - 'XPC_SERVICE_NAME': '0', 03:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:20:19 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 03:20:19 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 03:20:19 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 03:20:19 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 03:20:19 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 03:20:19 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 03:20:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:20:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:20:22 INFO - Installing collected packages: mozsystemmonitor 03:20:22 INFO - Running setup.py install for mozsystemmonitor 03:20:22 INFO - Successfully installed mozsystemmonitor 03:20:22 INFO - Cleaning up... 03:20:22 INFO - Return code: 0 03:20:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:20:22 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')]} 03:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:22 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')]} 03:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:22 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:22 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 03:20:22 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 03:20:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:22 INFO - 'HOME': '/Users/cltbld', 03:20:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:22 INFO - 'LOGNAME': 'cltbld', 03:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:22 INFO - 'MOZ_NO_REMOTE': '1', 03:20:22 INFO - 'NO_EM_RESTART': '1', 03:20:22 INFO - 'PAGER': '/bin/cat', 03:20:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:22 INFO - 'PWD': '/builds/slave/test', 03:20:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:22 INFO - 'SHELL': '/bin/bash', 03:20:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:22 INFO - 'USER': 'cltbld', 03:20:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:22 INFO - 'XPC_FLAGS': '0x0', 03:20:22 INFO - 'XPC_SERVICE_NAME': '0', 03:20:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:22 INFO - Downloading/unpacking jsonschema==2.5.1 03:20:22 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 03:20:22 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 03:20:22 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 03:20:22 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 03:20:22 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 03:20:22 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 03:20:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:20:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:20:25 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 03:20:25 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 03:20:25 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 03:20:25 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 03:20:25 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 03:20:25 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 03:20:25 INFO - Downloading functools32-3.2.3-2.tar.gz 03:20:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:20:25 INFO - warning: no files found matching '*.txt' 03:20:25 INFO - no previously-included directories found matching 'build' 03:20:25 INFO - no previously-included directories found matching 'dist' 03:20:25 INFO - no previously-included directories found matching '.git*' 03:20:25 INFO - Installing collected packages: jsonschema, functools32 03:20:25 INFO - Running setup.py install for functools32 03:20:25 INFO - warning: no files found matching '*.txt' 03:20:25 INFO - no previously-included directories found matching 'build' 03:20:25 INFO - no previously-included directories found matching 'dist' 03:20:25 INFO - no previously-included directories found matching '.git*' 03:20:25 INFO - Successfully installed jsonschema functools32 03:20:25 INFO - Cleaning up... 03:20:25 INFO - Return code: 0 03:20:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:20:25 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')]} 03:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:25 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')]} 03:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:25 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:25 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 03:20:25 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 03:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:25 INFO - 'HOME': '/Users/cltbld', 03:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:25 INFO - 'LOGNAME': 'cltbld', 03:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:25 INFO - 'MOZ_NO_REMOTE': '1', 03:20:25 INFO - 'NO_EM_RESTART': '1', 03:20:25 INFO - 'PAGER': '/bin/cat', 03:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:25 INFO - 'PWD': '/builds/slave/test', 03:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:25 INFO - 'SHELL': '/bin/bash', 03:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:25 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:25 INFO - 'USER': 'cltbld', 03:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:25 INFO - 'XPC_FLAGS': '0x0', 03:20:25 INFO - 'XPC_SERVICE_NAME': '0', 03:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:20:26 INFO - Cleaning up... 03:20:26 INFO - Return code: 0 03:20:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:20: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')]} 03:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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')]} 03:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:26 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:26 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 03:20:26 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 03:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:26 INFO - 'HOME': '/Users/cltbld', 03:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:26 INFO - 'LOGNAME': 'cltbld', 03:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:26 INFO - 'MOZ_NO_REMOTE': '1', 03:20:26 INFO - 'NO_EM_RESTART': '1', 03:20:26 INFO - 'PAGER': '/bin/cat', 03:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:26 INFO - 'PWD': '/builds/slave/test', 03:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:26 INFO - 'SHELL': '/bin/bash', 03:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:26 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:26 INFO - 'USER': 'cltbld', 03:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:26 INFO - 'XPC_FLAGS': '0x0', 03:20:26 INFO - 'XPC_SERVICE_NAME': '0', 03:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:26 INFO - Downloading/unpacking blobuploader==1.2.4 03:20:26 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 03:20:26 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 03:20:26 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 03:20:26 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 03:20:26 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 03:20:26 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 03:20:28 INFO - Downloading blobuploader-1.2.4.tar.gz 03:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:20:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:20:28 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 03:20:28 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 03:20:28 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 03:20:28 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 03:20:28 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 03:20:28 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 03:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:20:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:20:29 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 03:20:29 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 03:20:29 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 03:20:29 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 03:20:29 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 03:20:29 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 03:20:30 INFO - Downloading docopt-0.6.1.tar.gz 03:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:20:30 INFO - Installing collected packages: blobuploader, requests, docopt 03:20:30 INFO - Running setup.py install for blobuploader 03:20:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:20:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:20:30 INFO - Running setup.py install for requests 03:20:30 INFO - Running setup.py install for docopt 03:20:31 INFO - Successfully installed blobuploader requests docopt 03:20:31 INFO - Cleaning up... 03:20:31 INFO - Return code: 0 03:20:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:31 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:31 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 03:20:31 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 03:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:31 INFO - 'HOME': '/Users/cltbld', 03:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:31 INFO - 'LOGNAME': 'cltbld', 03:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:31 INFO - 'MOZ_NO_REMOTE': '1', 03:20:31 INFO - 'NO_EM_RESTART': '1', 03:20:31 INFO - 'PAGER': '/bin/cat', 03:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:31 INFO - 'PWD': '/builds/slave/test', 03:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:31 INFO - 'SHELL': '/bin/bash', 03:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:31 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:31 INFO - 'USER': 'cltbld', 03:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:31 INFO - 'XPC_FLAGS': '0x0', 03:20:31 INFO - 'XPC_SERVICE_NAME': '0', 03:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-At3rdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-La84op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-oV9jtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YYmP1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Fy9mEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-RRjweS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:20:31 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-6BwCF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7VFQ5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fjZnob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ryfTf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-sgEMFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-81H1jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-cAC4nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NmBVjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Y7X2kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ZBPKJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YwHgFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:20:33 INFO - Running setup.py install for manifestparser 03:20:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:20:33 INFO - Running setup.py install for mozcrash 03:20:33 INFO - Running setup.py install for mozdebug 03:20:33 INFO - Running setup.py install for mozdevice 03:20:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:20:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:20:33 INFO - Running setup.py install for mozfile 03:20:33 INFO - Running setup.py install for mozhttpd 03:20:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Running setup.py install for mozinfo 03:20:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Running setup.py install for mozInstall 03:20:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Running setup.py install for mozleak 03:20:34 INFO - Running setup.py install for mozlog 03:20:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Running setup.py install for moznetwork 03:20:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:20:34 INFO - Running setup.py install for mozprocess 03:20:34 INFO - Running setup.py install for mozprofile 03:20:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:20:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:20:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:20:35 INFO - Running setup.py install for mozrunner 03:20:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:20:35 INFO - Running setup.py install for mozscreenshot 03:20:35 INFO - Running setup.py install for moztest 03:20:35 INFO - Running setup.py install for mozversion 03:20:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:20:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:20:35 INFO - Cleaning up... 03:20:35 INFO - Return code: 0 03:20:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:35 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:35 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 03:20:35 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 03:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:35 INFO - 'HOME': '/Users/cltbld', 03:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:35 INFO - 'LOGNAME': 'cltbld', 03:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:35 INFO - 'MOZ_NO_REMOTE': '1', 03:20:35 INFO - 'NO_EM_RESTART': '1', 03:20:35 INFO - 'PAGER': '/bin/cat', 03:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:35 INFO - 'PWD': '/builds/slave/test', 03:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:35 INFO - 'SHELL': '/bin/bash', 03:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:35 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:35 INFO - 'USER': 'cltbld', 03:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:35 INFO - 'XPC_FLAGS': '0x0', 03:20:35 INFO - 'XPC_SERVICE_NAME': '0', 03:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Z2j1KS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-da9q0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-246Oqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-d8Gyzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-itVK7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-GrFClP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-vKOyN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-2oS8Jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-aqkVoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:36 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)) 03:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-0PhpIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-d0EE9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-RtFHCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-AAuwGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-TYz6Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ycgpMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-6BkTh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:37 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)) 03:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:37 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-TkzJSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:37 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)) 03:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:20:37 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)) 03:20:37 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)) 03:20:37 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)) 03:20:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:20:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:40 INFO - Downloading blessings-1.6.tar.gz 03:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:20:40 INFO - Installing collected packages: blessings 03:20:40 INFO - Running setup.py install for blessings 03:20:40 INFO - Successfully installed blessings 03:20:40 INFO - Cleaning up... 03:20:40 INFO - Return code: 0 03:20:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:20:40 INFO - Reading from file tmpfile_stdout 03:20:40 INFO - Current package versions: 03:20:40 INFO - blessings == 1.6 03:20:40 INFO - blobuploader == 1.2.4 03:20:40 INFO - docopt == 0.6.1 03:20:40 INFO - functools32 == 3.2.3-2 03:20:40 INFO - jsonschema == 2.5.1 03:20:40 INFO - manifestparser == 1.1 03:20:40 INFO - mozInstall == 1.12 03:20:40 INFO - mozcrash == 0.17 03:20:40 INFO - mozdebug == 0.1 03:20:40 INFO - mozdevice == 0.48 03:20:40 INFO - mozfile == 1.2 03:20:40 INFO - mozhttpd == 0.7 03:20:40 INFO - mozinfo == 0.9 03:20:40 INFO - mozleak == 0.1 03:20:40 INFO - mozlog == 3.4 03:20:40 INFO - moznetwork == 0.27 03:20:40 INFO - mozprocess == 0.23 03:20:40 INFO - mozprofile == 0.28 03:20:40 INFO - mozrunner == 6.12 03:20:40 INFO - mozscreenshot == 0.1 03:20:40 INFO - mozsystemmonitor == 0.3 03:20:40 INFO - moztest == 0.7 03:20:40 INFO - mozversion == 1.4 03:20:40 INFO - psutil == 3.1.1 03:20:40 INFO - requests == 1.2.3 03:20:40 INFO - wsgiref == 0.1.2 03:20:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:40 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')]} 03:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:40 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')]} 03:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:40 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:40 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 03:20:40 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 03:20:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:40 INFO - 'HOME': '/Users/cltbld', 03:20:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:40 INFO - 'LOGNAME': 'cltbld', 03:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:40 INFO - 'MOZ_NO_REMOTE': '1', 03:20:40 INFO - 'NO_EM_RESTART': '1', 03:20:40 INFO - 'PAGER': '/bin/cat', 03:20:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:40 INFO - 'PWD': '/builds/slave/test', 03:20:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:40 INFO - 'SHELL': '/bin/bash', 03:20:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:40 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:40 INFO - 'USER': 'cltbld', 03:20:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:40 INFO - 'XPC_FLAGS': '0x0', 03:20:40 INFO - 'XPC_SERVICE_NAME': '0', 03:20:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 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)) 03:20:41 INFO - Cleaning up... 03:20:41 INFO - Return code: 0 03:20:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:20:41 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')]} 03:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:41 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')]} 03:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:41 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 0x10b854490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f849ad6d380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.ixngYvuI9h/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.HNnVNsiixD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 03:20:41 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 03:20:41 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 03:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:41 INFO - 'HOME': '/Users/cltbld', 03:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:41 INFO - 'LOGNAME': 'cltbld', 03:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:41 INFO - 'MOZ_NO_REMOTE': '1', 03:20:41 INFO - 'NO_EM_RESTART': '1', 03:20:41 INFO - 'PAGER': '/bin/cat', 03:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:41 INFO - 'PWD': '/builds/slave/test', 03:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:41 INFO - 'SHELL': '/bin/bash', 03:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:20:41 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:41 INFO - 'USER': 'cltbld', 03:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:41 INFO - 'XPC_FLAGS': '0x0', 03:20:41 INFO - 'XPC_SERVICE_NAME': '0', 03:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:20:41 INFO - Cleaning up... 03:20:41 INFO - Return code: 0 03:20:41 INFO - Running post-action listener: _resource_record_post_action 03:20:41 INFO - Running post-action listener: _start_resource_monitoring 03:20:41 INFO - Starting resource monitoring. 03:20:41 INFO - [mozharness: 2016-10-15 10:20:41.543476Z] Finished create-virtualenv step (success) 03:20:41 INFO - [mozharness: 2016-10-15 10:20:41.544501Z] Running install step. 03:20:41 INFO - Running pre-action listener: _resource_record_pre_action 03:20:41 INFO - Running main action method: install 03:20:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:20:41 INFO - Reading from file tmpfile_stdout 03:20:41 INFO - Detecting whether we're running mozinstall >=1.0... 03:20:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:20:41 INFO - Reading from file tmpfile_stdout 03:20:41 INFO - Output received: 03:20:41 INFO - Usage: mozinstall [options] installer 03:20:41 INFO - Options: 03:20:41 INFO - -h, --help show this help message and exit 03:20:41 INFO - -d DEST, --destination=DEST 03:20:41 INFO - Directory to install application into. [default: 03:20:41 INFO - "/builds/slave/test"] 03:20:41 INFO - --app=APP Application being installed. [default: firefox] 03:20:41 INFO - mkdir: /builds/slave/test/build/application 03:20:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:21:15 INFO - Reading from file tmpfile_stdout 03:21:15 INFO - Output received: 03:21:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:21:15 INFO - Running post-action listener: _resource_record_post_action 03:21:15 INFO - [mozharness: 2016-10-15 10:21:15.791103Z] Finished install step (success) 03:21:15 INFO - [mozharness: 2016-10-15 10:21:15.791257Z] Running run-tests step. 03:21:15 INFO - Running pre-action listener: _resource_record_pre_action 03:21:15 INFO - Running main action method: run_tests 03:21:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:15 INFO - 2016-10-15 03:21:15.805 screenresolution[2272:24674] starting screenresolution argv=screenresolution get 03:21:15 INFO - 2016-10-15 03:21:15.807 screenresolution[2272:24674] Display 0: 1600x1200x32@60 03:21:15 INFO - 2016-10-15 03:21:15.813 screenresolution[2273:24677] starting screenresolution argv=screenresolution list 03:21:15 INFO - Available Modes on Display 0 03:21:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:16 INFO - Intel Iris: 03:21:16 INFO - Chipset Model: Intel Iris 03:21:16 INFO - Type: GPU 03:21:16 INFO - Bus: Built-In 03:21:16 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:16 INFO - Vendor: Intel (0x8086) 03:21:16 INFO - Device ID: 0x0a2e 03:21:16 INFO - Revision ID: 0x0009 03:21:16 INFO - Displays: 03:21:16 INFO - Display: 03:21:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:16 INFO - Display Serial Number: 1600x1200 60 03:21:16 INFO - Main Display: Yes 03:21:16 INFO - Mirror: Off 03:21:16 INFO - Online: Yes 03:21:16 INFO - Rotation: Supported 03:21:16 INFO - Return code: 0 03:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:21:16 INFO - Python 2.7.3 03:21:16 INFO - Return code: 0 03:21:16 INFO - grabbing minidump binary from tooltool 03:21:16 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')]} 03:21:16 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 0x10b668d30>, '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 0x10b48edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:21:16 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 03:21:16 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 03:21:16 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 03:21:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:21:16 INFO - Return code: 0 03:21:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:21:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:21:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:21:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:21:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 03:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --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 03:21:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ixngYvuI9h/Render', 03:21:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:16 INFO - 'HOME': '/Users/cltbld', 03:21:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:16 INFO - 'LOGNAME': 'cltbld', 03:21:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:21:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:16 INFO - 'MOZ_NO_REMOTE': '1', 03:21:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:21:16 INFO - 'NO_EM_RESTART': '1', 03:21:16 INFO - 'PAGER': '/bin/cat', 03:21:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:16 INFO - 'PWD': '/builds/slave/test', 03:21:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:21:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:16 INFO - 'SHELL': '/bin/bash', 03:21:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HNnVNsiixD/Listeners', 03:21:16 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 03:21:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:16 INFO - 'USER': 'cltbld', 03:21:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:16 INFO - 'XPC_FLAGS': '0x0', 03:21:16 INFO - 'XPC_SERVICE_NAME': '0', 03:21:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 03:21:16 INFO - mozversion application_buildid: 20161015021535 03:21:16 INFO - mozversion application_changeset: 44b01519b5668699f2c1a10d362445be59711d35 03:21:16 INFO - mozversion application_display_name: Nightly 03:21:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:21:16 INFO - mozversion application_name: Firefox 03:21:16 INFO - mozversion application_remotingname: firefox 03:21:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:21:16 INFO - mozversion application_vendor: Mozilla 03:21:16 INFO - mozversion application_version: 52.0a1 03:21:16 INFO - mozversion platform_buildid: 20161015021535 03:21:16 INFO - mozversion platform_changeset: 44b01519b5668699f2c1a10d362445be59711d35 03:21:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:21:16 INFO - mozversion platform_version: 52.0a1 03:21:16 INFO - using testdate: 1476526876 03:21:16 INFO - actual date: 1476526876 03:21:16 INFO - starting webserver on 'localhost:51732' 03:21:16 INFO - SUITE-START | Running 2 tests 03:21:16 INFO - TEST-START | dromaeo_css 03:21:16 INFO - operating with platform_type : mac_ 03:21:16 INFO - Initialising browser for dromaeo_css test... 03:21:16 INFO - TEST-INFO | started process 2283 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpjbU6Oy/profile http://localhost:51732/getInfo.html) 03:21:18 INFO - PROCESS | 2283 | __metrics Screen width/height:1600/1200 03:21:18 INFO - PROCESS | 2283 | colorDepth:24 03:21:18 INFO - PROCESS | 2283 | Browser inner width/height: 1024/689 03:21:18 INFO - PROCESS | 2283 | __metrics 03:21:18 INFO - PROCESS | 2283 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:21:18 INFO - PROCESS | 2283 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:21:19 INFO - TEST-INFO | 2283: exit 0 03:21:19 INFO - Browser initialized. 03:21:19 INFO - Running cycle 1/1 for dromaeo_css test... 03:21:19 INFO - TEST-INFO | started process 2285 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpjbU6Oy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:31 INFO - PROCESS | 2285 | RSS: Main: 238936064 03:21:31 INFO - PROCESS | 2285 | 03:22:23 INFO - PROCESS | 2285 | 1476526943034 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] 03:24:36 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51732/tests/dromaeo/cssquery-ext.html) 03:24:37 INFO - PROCESS | 2285 | RSS: Main: 396402688 03:24:37 INFO - PROCESS | 2285 | 03:27:40 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-ext.html (next: http://localhost:51732/tests/dromaeo/cssquery-jquery.html) 03:27:41 INFO - PROCESS | 2285 | RSS: Main: 379531264 03:27:41 INFO - PROCESS | 2285 | 03:30:43 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51732/tests/dromaeo/cssquery-mootools.html) 03:30:44 INFO - PROCESS | 2285 | RSS: Main: 359542784 03:30:44 INFO - PROCESS | 2285 | 03:33:46 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51732/tests/dromaeo/cssquery-prototype.html) 03:33:47 INFO - PROCESS | 2285 | RSS: Main: 341995520 03:33:47 INFO - PROCESS | 2285 | 03:36:50 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51732/tests/dromaeo/cssquery-yui.html) 03:36:51 INFO - PROCESS | 2285 | RSS: Main: 333864960 03:36:51 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | Cycle 1(1): loaded http://localhost:51732/tests/dromaeo/cssquery-yui.html (next: http://localhost:51732/tests/dromaeo/cssquery-dojo.html) 03:39:54 INFO - PROCESS | 2285 | RSS: Main: 454799360 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | __start_tp_report 03:39:54 INFO - PROCESS | 2285 | _x_x_mozilla_page_load 03:39:54 INFO - PROCESS | 2285 | _x_x_mozilla_page_load_details 03:39:54 INFO - PROCESS | 2285 | |i|pagename|runs| 03:39:54 INFO - PROCESS | 2285 | |0;dojo.html;2835.851480150539;3228.4673028950224;3247.6752324767526;3256.413845507809;3270.9345813083746;5432.049391356507;5480.095784195608;5546.278983732119;5579.804706835256;5620.803271885479;4697.812087516495;5525.198846167314;5546.500814926656;5633.042382794924;5637.915431268524;4994.400671919375;5010;5057.216131499624;5087.033463641897;5091.898162036757;4474.731516109044;5064.366954130734;5184.300119483878;5190.351206099238;5242.475752424746;5034.244863270539;5144.588432925357;5172.27588137661;5180.326557547513;5271.235670827747;5044.9747751261375;5112.079825631385;5117.48825117489;5126.53861152498;5129.461406552296;5024;5072.365954255718;5073.568746656539;5091;5092.668976516515;5524.06090964533;5526.364468086141;5550.5282051025715;5551;5556.222128901957;6025.0359942409;6029.698515074228;6032.66820324882;6034.426729460694;6036.788712395045;4812.687175333592;6090.725917333731;6095.952232173858;6103.4588915260565;6124.472479235446;5575.358833734092;5595.468430499096;5602.1036634139155;5640.774369025234;5652.095664693629;6202.069689546604;6206.100115483229;6218.9378106218355;6222.937770622327;6229.875402491924;5980.581359304894;5984.73068711909;6001.399860013964;6014.9699251504335;6034.6982650867285;6008.098785182257;6029.78895738647;6046.516278697693;6056.36408177135;6096.817095487142;6019.067044608005;6023.48800351975;6041.516678665696;6053.45518903292;6080.452759251689;5550.47270509301;5621.353544342372;6018.879622407615;6033.5173186145;6038.064100064497;4661.160991021648;4704.247320428715;4726.172919739032;4731.905361892743;4785.641076919175;4246.299360605527;4269.380939763717;4309.245881970643;4322.092360604245;4349.499807522176;5578.497935185853;5585.58108141891;5592.720363981784;5602.327720673546;5608.691521966331;6094.116353128771;6139.386061393825;6144.631322120687;6146.692665366714;6147.938520614736;5163.328767260232;5429.348478182565;5527.889442211133;5569.805056823073;5611.7474713637985;4781.378420805273;5259.474052594786;5261.684298942076;5277.287566178594;5277.736113194402;6263.342349053375;6269.028300613412;6275.96862015687;6294.937050629526;6302.275238347465;6041.577089603686;6053.273607166992;6065.3934606539;6127.724252408563;6152.477039451696;6105.967308275296;6113.067876544698;6115.960286751174;6126.059675755388;6145.432914606926;6034.125051867542;6055.969720151371;6058.242719660043;6087.360827113266;6115.480184184395;5973.850653733692;6034.607750496204;6057.273127224761;6059.030555111161;6081.787137450168;5701.800436984686;5768.394318596487;5840.970795145997;5905.734241959036;5917.408259174048;5803.9419605803405;5911.083782013795;5939.287285525765;5949.25634295713;5961.314448838439;5760.798372057126;5765.077587585966;5773.336066352424;5774.769009239583;5779.68211748341;5746.741396637078;5785.247917770663;5899.4985426239255;5934.169216309807;5954.404559544011;5635.746391412478;5684.9431505684415;5716.114002329646;5728.914066289092;5730.71346432685 03:39:54 INFO - PROCESS | 2285 | |1;ext.html;437.9540148284428;470.71286515225717;497.8182963218424;507.13027158423296;524.824183898394;29015.274618134557;31988.36023279539;31991.040268791974;31991.68008319916;32373.51439728406;41302;42215.577844221625;42261.57738422615;44377.55622443774;44392.55607443924;9540.90459095409;9698.418094914328;9701.029897010312;9707.70876873695;9716.125548700615;9289.117533834275;9408.81182376352;9411.576479058425;9412.952935235315;9425.905740942606;9400.764980875465;9429.198518125968;9430.387024843398;9440.527973601327;9467.573959171854;9347.95326023369;9405.952970235174;9412.57643406045;9418.19945304643;9420.811583768354;9362.672306469241;9438.056194380575;9453.33826632136;9455.66905158323;9459.054094590554;30527.542086868714;38202.42696359557;38452.42321365182;38560;38591.61408385908;3302.884399046022;3366;3433.626549380243;3499.475078738184;3735.4396840474146;28192.295192620142;29348;29388.412231755246;29433;29463.558046629318;3183.4747266700965;3246.9025929221925;3265.4775235962134;3317.5189597508465;3352.2960178362573;468051;468056;468724;471055;471709;59479;59751.701241493516;59823;59853.700731496065;59929;63049.68475157595;63144.68427657832;63148.36851631517;63325.68337158377;63382.68308658427;16245.187740612922;16448.83551164497;16462;16503.587410314598;16528.00831950087;12783.93608031954;12874.485020599072;12885.613431597067;12891.162074465317;12905.419256133497;16471.588210294838;16740.41408550695;16782.328706851833;16785.412510562073;16790.412335568442;6596.967015164893;6626.9065604174775;6791.626460544619;6844.349786770249;7236.819079523054;858.3948316436897;908.6229214875884;973.7419583810296;998.5406712912142;1046.4349251404308;25152.37119072038;29221;29685.703142968294;30346;31695.52456713151;1047.7537778622018;1069.6844430892904;1073.6993641780157;1090.334895713614;1095.8794532601407;14339.52085040059;14732.042417242845;15345.772338212917;15631.84368156304;15773.526794196192;582680;587386;590573;591815;594572;56851.75649530795;57108.42891571031;57183.428165718644;57186;57298.42701572931;1263.4567136131368;1307.7842156044337;1310.9934450327687;1349.912255703376;1397.8741913227655;28868.422631546833;29101;29411;29857.25356866096;30038.399232015676;48876.2668559979;49339.50660493349;49390.320113187445;49445.25832112593;49510.51468455952;475858.62070689426;476036;476927.61536192097;478493;479389;33961.66038339684;34881.825590871886;35121.297574048884;39081.80459097686;39539.011524712114;42081.57918420899;42264.78867605642;42382.57617423786;43116.568834311256;43957;31319.373612527164;35994.92015239547;38393.23213535769;39000.6099938997;40159.59840401675;37839.24321513609;38164.61835381722;38288.873780195034;39379.80310098431;39730.40404393994;31493;34838.505194972255;37955.43066853944;38157.80921095488;38200.61799381971;29757.851210743807;31810.363792723554;33924.66075339215;34140.97577072692;34166;25021.74978250194;25291.367715806515;25338;25348.619770703823;26010.73989260083 03:39:54 INFO - PROCESS | 2285 | |2;jquery.html;362.233875353627;369.7282497364437;371.9776813391195;388.35339160298105;399.8900302416835;18978;20186.59626807465;20475.897620511914;20721;20925.686114708293;20550.69173962392;20942.057607407663;21103.472413189676;21397.358079257647;21666.783332166673;5585.972070139644;5873.177755114288;7303.8539229215385;7676.577788221645;7803.258690424429;4548.363229147905;4771.475137734864;7652.617369131549;7801.843963120734;7817.140114587391;4811.109944660231;7756.728514501993;7780;7842.568658723768;7857.292843644071;7453.291937265951;7702.075750909927;7750.263724946121;7759.30166285037;7840.764777056669;7336.193018767931;7407.701153983422;7474.327310542078;7497.962510187469;7499.362554182901;28033.57949630757;29324.120276391743;29407.85296073506;29444.70555294441;29814.254643633867;2261.0729600863565;2320.8259380546356;2394.3535245483795;2686.2209959111833;2912.184588315275;13413.786725190332;16686.33254669812;16888.662226755518;17438;17631.735523967152;1944.4652720501833;1959.6080783843147;1988.2643421933833;2002.1690998235715;2030.157484643871;76438.61780691116;130659.34670326683;130933.34533327368;134815;135826.96259556114;16906.492805215865;19637.60724785496;20779.79220207809;20969.161233550793;21146.894265528575;34650.13374665654;34707.82646086753;34727.65272347244;34858.825705871306;34863.30273394518;10672.94663526693;10827;11005.559777608805;11029.062529685067;11180.161487888441;9009.504477253813;9039.45763254207;9047.954760226157;9218.907810921804;9248.630054797868;9511.476868772284;9563.234941204686;9584.616615335448;9690.854637180448;9732.90267097334;2894.3487715263896;2900.1583994163434;3128.5463607776746;3619.94570081449;3699.2231631357176;852.6887685994567;930.6184464369711;962.4610218277787;974.8001659659752;1041.9635312764017;19759.308424205083;21408.250711225344;21543.030563624674;21569.558027886244;21646.350609481742;927.76871623745;954.6515521834481;975.3416443900339;991.7669347703284;991.9008099190023;8752.8687069694;11019.779604407868;11083.612073577548;11167.168186195968;11268.943655281671;563063;568715;568787;578673;582336;98513.50743246381;134035;138891;139734.3013284927;139791.30104349414;1005.527402121002;1032.8708911386077;1061.686802393296;1066.195022757813;1070.2936062199067;18231.179596917904;18391;18668.7199692002;18682.906585466986;18836.058197090366;52618.73690631522;53799.46200537944;54402.911941760154;54802.72598636981;55829;80520.59739701264;82487.5875620642;83380.58309708412;84832;86479;5492.670439773467;6655.56738811995;6979.581225126509;7407.111146662435;7522.435817313833;7283.308085731847;10449.477526123512;10612.469376531297;12836.844683978487;13430.93284533571;11029.283096598696;11142.997130258316;11233.213675042669;11661.475233614678;11803.409829508662;11063.114950804078;11066.39134847591;11114.444277785917;11514.712132196428;11521.366324851879;10910.890891090987;11324.980751732384;11454;11801.350925698825;11880.287182769061;6896.344847239504;7663.961680191564;7988.680452781824;8187.79530511742;8306.916930830856;9467.621295148117;10468.214883883587;10752.301100428447;11142.610008649659;11360.715982100182 03:39:54 INFO - PROCESS | 2285 | |3;mootools.html;1501.0243341827806;1523.87808975282;1748.9737653935192;1849.6855534559124;1931.4205738278513;8423.789405264872;8779.824403511926;8795.472271663688;8855.95572022139;8871.713601527783;7401.962990185055;8102.5543595102245;8124.837503249932;8521.616527256272;8746.475211487315;2313.618252988259;2322.7677232276724;2354.3290162303747;2361.3388251289666;2363.456405026845;2333.2416964486524;2335.7664233576593;2383.344580240439;2383.916562920298;2384.2132096408145;2359.6932398788217;2368.7157541095007;2381.1784934197763;2385.5705972924866;2388.22382725614;2335.089315167086;2356.0222507659387;2362.9291121266388;2367.656689779972;2370.8221883358815;2332.207049603126;2346.0263990443946;2350.2361732437025;2358;2362.7637236276405;8636.481811091355;8744.69393571222;8757.56212189388;8783.956080219557;8790.956045219797;5121;5289.418164001957;5292.285541451932;5294.470552944675;5301.761420736076;3518.0852978225844;3518.612952575215;3586.964130358715;3594.8741794037082;3596.29872174926;1044.2116202267316;1049.4018409506584;1050.2123407444417;1064.3454275620438;1075.0861767497643;11272.492737826817;11359.261647992937;11369.602063927805;11378.71553211168;11382.146339024606;4429.68992170545;4521.570450807168;4720.693154944917;4733.928991065137;4746.454157771831;9639.325247232766;9653.662121825842;9656.130948214695;9661.275404344702;9689.321747477608;5252.65857719247;5279.44565820591;5287.391949925808;5295.126304159848;5303.496167864047;4290.721103128349;4398.3402489626815;4526.479454862733;4540.228161212536;4840.564349208588;3807.3337165996395;3836.1176929305857;3908.804559772;3929.528456585228;3994.7003974702016;1075.8224892892586;1246.7506498700295;1264.3741348032784;1276.2087505746497;1281.1992504684572;819.5328662662345;891.4517571693361;895.5387975192781;913.7624217703445;955.3790036476346;5693.231413759172;5813.302403711582;5941.910871336933;5988.520918326523;6119.204503414617;436.09727863322615;439.3519558651028;443.7537166872417;917.5779141594808;1003.2124782046059;3418.931621367559;3909.2963266612282;4051.311277082903;4053.3786485405485;4057.2494088593703;9820.901790982141;10626.309289896286;10721.249512534203;10722.678319650267;10754.623588174376;10259.281850270409;10472.528736206888;10696.946515267375;10698.62554810578;10735.087517560938;8094.676212951534;8248.917510824935;8253.752387428387;8262.504249744914;8263.586820659064;5816.1857339973285;5816.272965879265;5893.44012318829;5960.314563825215;5966.582339236212;9529.285303602397;10022.949885250528;10063.446510441996;10069.647562335284;10072.395656260354;8657.437266577652;10160.898391015995;10222.182225422097;10231.539580718736;10283.537240824031;6547.181602299713;6770.424513916372;6935.549189302679;7060;7079.681414336264;7942.324902383131;8120.634571444417;8263.628136733982;8435.704750333709;8450.366222533212;6511.446527045254;6750.932490675227;7355.8161045974275;7434.8884766729625;7548.320651141424;6924.238333783179;7345.963270183614;7353.080864891888;7353.191148973501;7409.2590740927635;6451.41937225652;6878;6957.095577574883;7027.859442811217;7032.683529241299;4821.927671084866;5010.473900240422;5042.823501177441;5145.819896303611;5225.902560462346;5015.1975683890405;5072.340595722532;5089.745512724423;5125.7693403796175;5291.973540132275 03:39:54 INFO - PROCESS | 2285 | |4;prototype.html;300.61821486711875;307.9230192451887;311.752157035157;312.9186411533002;318.30132858375873;4655.394798676176;5317.760700768465;5376.8118115866;5404.513593776559;5409.810656627019;4600.539946005398;4837.177679794435;5336.849939808085;5359.758810853511;5363.570914326854;3730.8321125549414;3981.800909954505;4003.619656132663;4059.776712280823;4090.2637525245445;3436.79712100764;4068.5524592294823;4100.851761506783;4117.505899292089;4119.7454011331165;3399.9660003399895;3722.5532936047575;3796.354619714641;4063.4108054332105;4084.9591504085024;2891.580720795494;3489.6041583366605;4059.857904973341;4065.5934406559404;4066.1664358806374;2719.564869620871;2973.04862444018;2977;3490.3019396120876;3493.68556829884;4220.556841531657;5298.278059630636;5316.867078323073;5321.574274058066;5321.73391330432;4427.2695005324085;4516.209663308908;4637.234856248715;4641.537915556589;4658.34783130362;5112.923306150411;5624.621967617919;5654.830355089354;5657.23627310312;5670.432956704338;3254.8209848458328;3823.5794062653085;3823.9617603823885;3842.634949679776;3848.903777405559;4623.884402889921;4651.86044418668;4937.9755499267985;5122.463261021679;5895.498882594985;3199.376121656255;3377.902181790928;3770.472133901256;3879.739084797452;3901.239258344652;3539.8672424824326;3791.829367678461;3968.8809335719975;3973.145773658677;3977;4115.444415003936;4394.725529596381;4637.006087382458;4638.350630911675;4652.55800698933;5547.223388725582;5669.270872383962;5720.599558030898;5733.3406658234;5781.595288329777;1197.7305106351;1210.5823490895627;1243.6382161533106;1334.3861823561076;1339.4173534512324;791.782259878538;811.6266517401943;831.7920519870032;831.9292860106838;857.2927334948693;4206.684498662613;5111.284420181178;5152.3044389007;5271.288376069258;5298.470152984671;4958.206686930146;5861.736221870026;5861.798331342065;5892.587518873637;5902.8819423611285;4644.3265726470145;4735.289706544046;5090.567301779316;5337.893242135136;5344.839654810362;2920.8831646733893;3041.285297954979;3060.908172754821;3064.0195137556216;3067.1169101044948;4612.76936153191;5273.894522109613;5443.516149664218;5524.558035357162;6035.486983606354;5699.696728969684;5704.258446401941;5915.674637894957;5952.065578686944;5974.820755377345;4552.908941821212;5072.923906141411;5400.567954563626;5655.349634791969;6011.759529618854;5119.87200319995;5498.862528436661;5814.622049566765;5827.854303642443;5857.882842343214;4789.185838407409;5705.201271822031;5723.8804851356945;5746.310442746897;5747.396523364986;4953.876153096201;5025.748712564284;5980.700964951648;5987.6169963970215;6126.969365153146;3576.535050443426;3800.486934263839;3805.4482100095884;3847.883214596186;3921.31377009028;5097.898042039213;5654.40628733977;5812.593118481665;5856.648601083949;5860.38465961085;5240.528352448146;5730.885382292247;5742.885142297214;5748.712564371848;5750.562359410147;4596.954030459653;5468.343798744176;5578.832635020955;5635.8591035224445;5641;4451.888702782457;5537.501624853782;5548.250986116984;5561.6663000220115;5587.357453892854;5369.758360873692;5398.757055932414;5562.805301814417;5579.40557640699;5767.653940763567;4390.670699697599;5528.806491772769;5564;5572.85756419941;5574.832755017356 03:39:54 INFO - PROCESS | 2285 | |5;yui.html;725.8729722298597;732.4543215304598;742.2985278911427;814.2671595563995;825.8190787174342;15831.762523562142;15884.046957182578;16050;16093.597660058504;16126.193690315482;11422;14030.228337441436;14085.295735213249;14444.350004249807;14467.204303763288;712.3588770106893;730.5178582135792;738.269113577557;739.548875186136;880.9823803523925;726.9382102521292;731.3162193349209;875.4747151708988;907.015887761776;907.9954600227022;955.8996305387939;965.5510187762688;971.028971028971;972.6255391674185;978.4765150644407;968.7578105473632;969.6751588217955;969.8011907558933;970.4710932541747;972.4311277902435;718.4575645387752;718.9856202875966;719.7696737044148;719.8272414620506;724.221461928429;11882.821757673642;12405.317707526083;14596.34316455762;15189.544313670607;15195.240237988168;13862.722745545134;14690.338934747962;14743.410263589443;14893.702125957529;14920.70158596822;11698.47356868943;11701.2979221247;11741.823872641917;11786.528538858436;12248.571300004547;3797.35444974355;3818.2554401891643;3862.4592557042038;3962.6829853611644;3976.1451287973223;119014;119394;119749.80250197473;119826;120550;9831.45926974009;10609.045185933302;11578.421078946187;11698.590549330733;11976.401179941142;21363.718176909435;23914.40213994664;24389.390265243157;24400.90195941187;24424;7043.295670432915;7797.259260370256;8045.517268963881;8087.83824323522;8119.878201826978;4376.606105450524;4386.912261754811;4435.756033418128;4445.888852778642;4578.587927086578;3617.2584620152807;3723.106454450912;3775.9433608495897;3781.905452363713;3787.0721673189696;667.5222092896066;672.525869262169;673.7204060315013;681.1485642946317;685.9759908403182;625.43710660406;666.5567397680585;675.1796567170812;675.4798804920184;678.3860606151441;7933.9603301983125;9075.863862042075;9539.57071931751;9608.807823843625;9619.759506012266;554.0829926471696;644.7839973608893;671.1174805131237;681.3493114076045;683.8598087392073;4129.504459464883;4254.574542545783;4260.4248426462655;4591.380163677927;4595.839145629887;92909.5354523223;95154;96164;117105.3881497717;117851.41074294574;120298;124571;129987;130170;135852.64147358597;111936;112557.74884502264;114151;115669.4216528912;119103.40448297876;7425.740099096506;8024.675928471736;8268.2558569729;8863.556822158736;9175.403598766059;62740.37259627528;63571.68214158899;63624.363756363695;64247;66476.00285995618;61172.69413652903;65888;77621.61189194018;93935.53032234796;95516.52241738747;12851.807222891477;12992.350382480648;13145.816876481163;13277.800832987707;13369.933150334185;14681.706365872837;14771.261436927896;14832.406703731729;14964.476243331432;15652.295646695698;13761.243131627456;13852.307384630527;14392.280385980868;14521.273936303354;14527.564173074825;13523.526676566273;13845.30773461343;13918.304084795518;14182.361793719514;14410.711785764435;13311.267880266678;13489.436252736026;13537;13672.931635342158;13731.862681373457;10153;10154.644587439405;10823.404712740874;11246.493907774333;11662.475188616363;11179.720506987065;11246.437678116225;11524.769504610029;11541.36522491271;11554.711132221426 03:39:54 INFO - PROCESS | 2285 | __end_tp_report 03:39:54 INFO - PROCESS | 2285 | __start_cc_report 03:39:54 INFO - PROCESS | 2285 | _x_x_mozilla_cycle_collect,1670 03:39:54 INFO - PROCESS | 2285 | __end_cc_report 03:39:54 INFO - PROCESS | 2285 | __startTimestamp1476527994829__endTimestamp 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | ------- Summary: start ------- 03:39:54 INFO - PROCESS | 2285 | Number of tests: 6 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#0] dojo.html Cycles:165 Average:5568.98 Median:5728.91 stddev:648.19 (11.3%) stddev-sans-first:613.70 03:39:54 INFO - PROCESS | 2285 | Values: 2835.9 3228.5 3247.7 3256.4 3270.9 5432.0 5480.1 5546.3 5579.8 5620.8 4697.8 5525.2 5546.5 5633.0 5637.9 4994.4 5010.0 5057.2 5087.0 5091.9 4474.7 5064.4 5184.3 5190.4 5242.5 5034.2 5144.6 5172.3 5180.3 5271.2 5045.0 5112.1 5117.5 5126.5 5129.5 5024.0 5072.4 5073.6 5091.0 5092.7 5524.1 5526.4 5550.5 5551.0 5556.2 6025.0 6029.7 6032.7 6034.4 6036.8 4812.7 6090.7 6096.0 6103.5 6124.5 5575.4 5595.5 5602.1 5640.8 5652.1 6202.1 6206.1 6218.9 6222.9 6229.9 5980.6 5984.7 6001.4 6015.0 6034.7 6008.1 6029.8 6046.5 6056.4 6096.8 6019.1 6023.5 6041.5 6053.5 6080.5 5550.5 5621.4 6018.9 6033.5 6038.1 4661.2 4704.2 4726.2 4731.9 4785.6 4246.3 4269.4 4309.2 4322.1 4349.5 5578.5 5585.6 5592.7 5602.3 5608.7 6094.1 6139.4 6144.6 6146.7 6147.9 5163.3 5429.3 5527.9 5569.8 5611.7 4781.4 5259.5 5261.7 5277.3 5277.7 6263.3 6269.0 6276.0 6294.9 6302.3 6041.6 6053.3 6065.4 6127.7 6152.5 6106.0 6113.1 6116.0 6126.1 6145.4 6034.1 6056.0 6058.2 6087.4 6115.5 5973.9 6034.6 6057.3 6059.0 6081.8 5701.8 5768.4 5841.0 5905.7 5917.4 5803.9 5911.1 5939.3 5949.3 5961.3 5760.8 5765.1 5773.3 5774.8 5779.7 5746.7 5785.2 5899.5 5934.2 5954.4 5635.7 5684.9 5716.1 5728.9 5730.7 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#1] ext.html Cycles:180 Average:64997.61 Median:39041.21 stddev:137233.18 (351.5%) stddev-sans-first:137532.55 03:39:54 INFO - PROCESS | 2285 | Values: 438.0 470.7 497.8 507.1 524.8 29015.3 31988.4 31991.0 31991.7 32373.5 41302.0 42215.6 42261.6 44377.6 44392.6 9540.9 9698.4 9701.0 9707.7 9716.1 9289.1 9408.8 9411.6 9413.0 9425.9 9400.8 9429.2 9430.4 9440.5 9467.6 9348.0 9406.0 9412.6 9418.2 9420.8 9362.7 9438.1 9453.3 9455.7 9459.1 30527.5 38202.4 38452.4 38560.0 38591.6 3302.9 3366.0 3433.6 3499.5 3735.4 28192.3 29348.0 29388.4 29433.0 29463.6 3183.5 3246.9 3265.5 3317.5 3352.3 468051.0 468056.0 468724.0 471055.0 471709.0 59479.0 59751.7 59823.0 59853.7 59929.0 63049.7 63144.7 63148.4 63325.7 63382.7 16245.2 16448.8 16462.0 16503.6 16528.0 12783.9 12874.5 12885.6 12891.2 12905.4 16471.6 16740.4 16782.3 16785.4 16790.4 6597.0 6626.9 6791.6 6844.3 7236.8 858.4 908.6 973.7 998.5 1046.4 25152.4 29221.0 29685.7 30346.0 31695.5 1047.8 1069.7 1073.7 1090.3 1095.9 14339.5 14732.0 15345.8 15631.8 15773.5 582680.0 587386.0 590573.0 591815.0 594572.0 56851.8 57108.4 57183.4 57186.0 57298.4 1263.5 1307.8 1311.0 1349.9 1397.9 28868.4 29101.0 29411.0 29857.3 30038.4 48876.3 49339.5 49390.3 49445.3 49510.5 475858.6 476036.0 476927.6 478493.0 479389.0 33961.7 34881.8 35121.3 39081.8 39539.0 42081.6 42264.8 42382.6 43116.6 43957.0 31319.4 35994.9 38393.2 39000.6 40159.6 37839.2 38164.6 38288.9 39379.8 39730.4 31493.0 34838.5 37955.4 38157.8 38200.6 29757.9 31810.4 33924.7 34141.0 34166.0 25021.7 25291.4 25338.0 25348.6 26010.7 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#2] jquery.html Cycles:180 Average:36136.33 Median:29426.28 stddev:95845.20 (325.7%) stddev-sans-first:96076.43 03:39:54 INFO - PROCESS | 2285 | Values: 362.2 369.7 372.0 388.4 399.9 18978.0 20186.6 20475.9 20721.0 20925.7 20550.7 20942.1 21103.5 21397.4 21666.8 5586.0 5873.2 7303.9 7676.6 7803.3 4548.4 4771.5 7652.6 7801.8 7817.1 4811.1 7756.7 7780.0 7842.6 7857.3 7453.3 7702.1 7750.3 7759.3 7840.8 7336.2 7407.7 7474.3 7498.0 7499.4 28033.6 29324.1 29407.9 29444.7 29814.3 2261.1 2320.8 2394.4 2686.2 2912.2 13413.8 16686.3 16888.7 17438.0 17631.7 1944.5 1959.6 1988.3 2002.2 2030.2 76438.6 130659.3 130933.3 134815.0 135827.0 16906.5 19637.6 20779.8 20969.2 21146.9 34650.1 34707.8 34727.7 34858.8 34863.3 10672.9 10827.0 11005.6 11029.1 11180.2 9009.5 9039.5 9048.0 9218.9 9248.6 9511.5 9563.2 9584.6 9690.9 9732.9 2894.3 2900.2 3128.5 3619.9 3699.2 852.7 930.6 962.5 974.8 1042.0 19759.3 21408.3 21543.0 21569.6 21646.4 927.8 954.7 975.3 991.8 991.9 8752.9 11019.8 11083.6 11167.2 11268.9 563063.0 568715.0 568787.0 578673.0 582336.0 98513.5 134035.0 138891.0 139734.3 139791.3 1005.5 1032.9 1061.7 1066.2 1070.3 18231.2 18391.0 18668.7 18682.9 18836.1 52618.7 53799.5 54402.9 54802.7 55829.0 80520.6 82487.6 83380.6 84832.0 86479.0 5492.7 6655.6 6979.6 7407.1 7522.4 7283.3 10449.5 10612.5 12836.8 13430.9 11029.3 11143.0 11233.2 11661.5 11803.4 11063.1 11066.4 11114.4 11514.7 11521.4 10910.9 11325.0 11454.0 11801.4 11880.3 6896.3 7664.0 7988.7 8187.8 8306.9 9467.6 10468.2 10752.3 11142.6 11360.7 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#3] mootools.html Cycles:180 Average:5628.01 Median:4831.25 stddev:3141.31 (65.0%) stddev-sans-first:3134.82 03:39:54 INFO - PROCESS | 2285 | Values: 1501.0 1523.9 1749.0 1849.7 1931.4 8423.8 8779.8 8795.5 8856.0 8871.7 7402.0 8102.6 8124.8 8521.6 8746.5 2313.6 2322.8 2354.3 2361.3 2363.5 2333.2 2335.8 2383.3 2383.9 2384.2 2359.7 2368.7 2381.2 2385.6 2388.2 2335.1 2356.0 2362.9 2367.7 2370.8 2332.2 2346.0 2350.2 2358.0 2362.8 8636.5 8744.7 8757.6 8784.0 8791.0 5121.0 5289.4 5292.3 5294.5 5301.8 3518.1 3518.6 3587.0 3594.9 3596.3 1044.2 1049.4 1050.2 1064.3 1075.1 11272.5 11359.3 11369.6 11378.7 11382.1 4429.7 4521.6 4720.7 4733.9 4746.5 9639.3 9653.7 9656.1 9661.3 9689.3 5252.7 5279.4 5287.4 5295.1 5303.5 4290.7 4398.3 4526.5 4540.2 4840.6 3807.3 3836.1 3908.8 3929.5 3994.7 1075.8 1246.8 1264.4 1276.2 1281.2 819.5 891.5 895.5 913.8 955.4 5693.2 5813.3 5941.9 5988.5 6119.2 436.1 439.4 443.8 917.6 1003.2 3418.9 3909.3 4051.3 4053.4 4057.2 9820.9 10626.3 10721.2 10722.7 10754.6 10259.3 10472.5 10696.9 10698.6 10735.1 8094.7 8248.9 8253.8 8262.5 8263.6 5816.2 5816.3 5893.4 5960.3 5966.6 9529.3 10022.9 10063.4 10069.6 10072.4 8657.4 10160.9 10222.2 10231.5 10283.5 6547.2 6770.4 6935.5 7060.0 7079.7 7942.3 8120.6 8263.6 8435.7 8450.4 6511.4 6750.9 7355.8 7434.9 7548.3 6924.2 7346.0 7353.1 7353.2 7409.3 6451.4 6878.0 6957.1 7027.9 7032.7 4821.9 5010.5 5042.8 5145.8 5225.9 5015.2 5072.3 5089.7 5125.8 5292.0 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#4] prototype.html Cycles:180 Average:4493.15 Median:5094.23 stddev:1415.40 (27.8%) stddev-sans-first:1383.95 03:39:54 INFO - PROCESS | 2285 | Values: 300.6 307.9 311.8 312.9 318.3 4655.4 5317.8 5376.8 5404.5 5409.8 4600.5 4837.2 5336.8 5359.8 5363.6 3730.8 3981.8 4003.6 4059.8 4090.3 3436.8 4068.6 4100.9 4117.5 4119.7 3400.0 3722.6 3796.4 4063.4 4085.0 2891.6 3489.6 4059.9 4065.6 4066.2 2719.6 2973.0 2977.0 3490.3 3493.7 4220.6 5298.3 5316.9 5321.6 5321.7 4427.3 4516.2 4637.2 4641.5 4658.3 5112.9 5624.6 5654.8 5657.2 5670.4 3254.8 3823.6 3824.0 3842.6 3848.9 4623.9 4651.9 4938.0 5122.5 5895.5 3199.4 3377.9 3770.5 3879.7 3901.2 3539.9 3791.8 3968.9 3973.1 3977.0 4115.4 4394.7 4637.0 4638.4 4652.6 5547.2 5669.3 5720.6 5733.3 5781.6 1197.7 1210.6 1243.6 1334.4 1339.4 791.8 811.6 831.8 831.9 857.3 4206.7 5111.3 5152.3 5271.3 5298.5 4958.2 5861.7 5861.8 5892.6 5902.9 4644.3 4735.3 5090.6 5337.9 5344.8 2920.9 3041.3 3060.9 3064.0 3067.1 4612.8 5273.9 5443.5 5524.6 6035.5 5699.7 5704.3 5915.7 5952.1 5974.8 4552.9 5072.9 5400.6 5655.3 6011.8 5119.9 5498.9 5814.6 5827.9 5857.9 4789.2 5705.2 5723.9 5746.3 5747.4 4953.9 5025.7 5980.7 5987.6 6127.0 3576.5 3800.5 3805.4 3847.9 3921.3 5097.9 5654.4 5812.6 5856.6 5860.4 5240.5 5730.9 5742.9 5748.7 5750.6 4597.0 5468.3 5578.8 5635.9 5641.0 4451.9 5537.5 5548.3 5561.7 5587.4 5369.8 5398.8 5562.8 5579.4 5767.7 4390.7 5528.8 5564.0 5572.9 5574.8 03:39:54 INFO - PROCESS | 2285 | 03:39:54 INFO - PROCESS | 2285 | [#5] yui.html Cycles:180 Average:23750.56 Median:3792.21 stddev:36718.31 (968.3%) stddev-sans-first:36780.62 03:39:54 INFO - PROCESS | 2285 | Values: 725.9 732.5 742.3 814.3 825.8 15831.8 15884.0 16050.0 16093.6 16126.2 11422.0 14030.2 14085.3 14444.4 14467.2 712.4 730.5 738.3 739.5 881.0 726.9 731.3 875.5 907.0 908.0 955.9 965.6 971.0 972.6 978.5 968.8 969.7 969.8 970.5 972.4 718.5 719.0 719.8 719.8 724.2 11882.8 12405.3 14596.3 15189.5 15195.2 13862.7 14690.3 14743.4 14893.7 14920.7 11698.5 11701.3 11741.8 11786.5 12248.6 3797.4 3818.3 3862.5 3962.7 3976.1 119014.0 119394.0 119749.8 119826.0 120550.0 9831.5 10609.0 11578.4 11698.6 11976.4 21363.7 23914.4 24389.4 24400.9 24424.0 7043.3 7797.3 8045.5 8087.8 8119.9 4376.6 4386.9 4435.8 4445.9 4578.6 3617.3 3723.1 3775.9 3781.9 3787.1 667.5 672.5 673.7 681.1 686.0 625.4 666.6 675.2 675.5 678.4 7934.0 9075.9 9539.6 9608.8 9619.8 554.1 644.8 671.1 681.3 683.9 4129.5 4254.6 4260.4 4591.4 4595.8 92909.5 95154.0 96164.0 117105.4 117851.4 120298.0 124571.0 129987.0 130170.0 135852.6 111936.0 112557.7 114151.0 115669.4 119103.4 7425.7 8024.7 8268.3 8863.6 9175.4 62740.4 63571.7 63624.4 64247.0 66476.0 61172.7 65888.0 77621.6 93935.5 95516.5 12851.8 12992.4 13145.8 13277.8 13369.9 14681.7 14771.3 14832.4 14964.5 15652.3 13761.2 13852.3 14392.3 14521.3 14527.6 13523.5 13845.3 13918.3 14182.4 14410.7 13311.3 13489.4 13537.0 13672.9 13731.9 10153.0 10154.6 10823.4 11246.5 11662.5 11179.7 11246.4 11524.8 11541.4 11554.7 03:39:54 INFO - PROCESS | 2285 | -------- Summary: end -------- 03:39:54 INFO - PROCESS | 2285 | 03:39:55 INFO - TEST-INFO | 2285: exit 0 03:39:55 INFO - TEST-OK | dromaeo_css | took 1119083ms 03:39:55 INFO - TEST-START | kraken 03:39:55 INFO - operating with platform_type : mac_ 03:39:55 INFO - Initialising browser for kraken test... 03:39:55 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp6JNAal/profile http://localhost:51732/getInfo.html) 03:39:57 INFO - PROCESS | 2311 | __metrics Screen width/height:1600/1200 03:39:57 INFO - PROCESS | 2311 | colorDepth:24 03:39:57 INFO - PROCESS | 2311 | Browser inner width/height: 1024/689 03:39:57 INFO - PROCESS | 2311 | __metrics 03:39:57 INFO - PROCESS | 2311 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:39:57 INFO - PROCESS | 2311 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:39:58 INFO - TEST-INFO | 2311: exit 0 03:39:58 INFO - Browser initialized. 03:39:58 INFO - Running cycle 1/1 for kraken test... 03:39:58 INFO - TEST-INFO | started process 2312 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp6JNAal/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:10 INFO - PROCESS | 2312 | RSS: Main: 239403008 03:40:10 INFO - PROCESS | 2312 | 03:40:13 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-beat-detection) 03:40:14 INFO - PROCESS | 2312 | RSS: Main: 290492416 03:40:14 INFO - PROCESS | 2312 | 03:40:16 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-dft) 03:40:17 INFO - PROCESS | 2312 | RSS: Main: 289517568 03:40:17 INFO - PROCESS | 2312 | 03:40:21 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-fft) 03:40:21 INFO - PROCESS | 2312 | RSS: Main: 383807488 03:40:21 INFO - PROCESS | 2312 | 03:40:23 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51732/tests/kraken/driver.html?testName=audio-oscillator) 03:40:24 INFO - PROCESS | 2312 | RSS: Main: 283848704 03:40:24 INFO - PROCESS | 2312 | 03:40:26 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:40:26 INFO - PROCESS | 2312 | RSS: Main: 288641024 03:40:26 INFO - PROCESS | 2312 | 03:40:29 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-darkroom) 03:40:30 INFO - PROCESS | 2312 | RSS: Main: 325627904 03:40:30 INFO - PROCESS | 2312 | 03:40:33 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51732/tests/kraken/driver.html?testName=imaging-desaturate) 03:40:33 INFO - PROCESS | 2312 | RSS: Main: 327331840 03:40:33 INFO - PROCESS | 2312 | 03:40:36 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51732/tests/kraken/driver.html?testName=json-parse-financial) 03:40:37 INFO - PROCESS | 2312 | RSS: Main: 325435392 03:40:37 INFO - PROCESS | 2312 | 03:40:38 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51732/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:40:39 INFO - PROCESS | 2312 | RSS: Main: 303124480 03:40:39 INFO - PROCESS | 2312 | 03:40:41 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:40:41 INFO - PROCESS | 2312 | RSS: Main: 305524736 03:40:41 INFO - PROCESS | 2312 | 03:40:44 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:40:44 INFO - PROCESS | 2312 | RSS: Main: 311091200 03:40:44 INFO - PROCESS | 2312 | 03:40:47 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:40:48 INFO - PROCESS | 2312 | RSS: Main: 317562880 03:40:48 INFO - PROCESS | 2312 | 03:40:51 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:40:51 INFO - PROCESS | 2312 | RSS: Main: 317353984 03:40:51 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | Cycle 1(1): loaded http://localhost:51732/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51732/tests/kraken/driver.html?testName=ai-astar) 03:40:53 INFO - PROCESS | 2312 | RSS: Main: 361062400 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | __start_tp_report 03:40:53 INFO - PROCESS | 2312 | _x_x_mozilla_page_load 03:40:53 INFO - PROCESS | 2312 | _x_x_mozilla_page_load_details 03:40:53 INFO - PROCESS | 2312 | |i|pagename|runs| 03:40:53 INFO - PROCESS | 2312 | |0;ai-astar;117;112;114;113;112;113;112;114;113;113 03:40:53 INFO - PROCESS | 2312 | |1;audio-beat-detection;151;120;140;175;115;113;114;110;142;109 03:40:53 INFO - PROCESS | 2312 | |2;audio-dft;181;182;184;185;184;184;181;183;182;184 03:40:53 INFO - PROCESS | 2312 | |3;audio-fft;83;59;59;89;61;58;58;58;85;57 03:40:53 INFO - PROCESS | 2312 | |4;audio-oscillator;85;71;92;70;69;69;92;69;70;91 03:40:53 INFO - PROCESS | 2312 | |5;imaging-gaussian-blur;88;86;89;86;98;120;90;86;89;86 03:40:53 INFO - PROCESS | 2312 | |6;imaging-darkroom;93;95;94;94;93;107;149;113;94;94 03:40:53 INFO - PROCESS | 2312 | |7;imaging-desaturate;82;81;79;82;83;80;85;85;80;80 03:40:53 INFO - PROCESS | 2312 | |8;json-parse-financial;58;56;56;55;53;54;53;55;53;53 03:40:53 INFO - PROCESS | 2312 | |9;json-stringify-tinderbox;64;76;81;72;72;69;68;73;70;73 03:40:53 INFO - PROCESS | 2312 | |10;stanford-crypto-aes;85;90;86;89;90;84;85;85;92;87 03:40:53 INFO - PROCESS | 2312 | |11;stanford-crypto-ccm;155;152;152;147;147;143;147;152;189;150 03:40:53 INFO - PROCESS | 2312 | |12;stanford-crypto-pbkdf2;174;171;171;170;179;174;172;171;172;175 03:40:53 INFO - PROCESS | 2312 | |13;stanford-crypto-sha256-iterative;67;68;68;68;66;65;60;65;70;82 03:40:53 INFO - PROCESS | 2312 | __end_tp_report 03:40:53 INFO - PROCESS | 2312 | __start_cc_report 03:40:53 INFO - PROCESS | 2312 | _x_x_mozilla_cycle_collect,1972 03:40:53 INFO - PROCESS | 2312 | __end_cc_report 03:40:53 INFO - PROCESS | 2312 | __startTimestamp1476528053971__endTimestamp 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | ------- Summary: start ------- 03:40:53 INFO - PROCESS | 2312 | Number of tests: 14 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.00 stddev:1.49 (1.3%) stddev-sans-first:0.78 03:40:53 INFO - PROCESS | 2312 | Values: 117.0 112.0 114.0 113.0 112.0 113.0 112.0 114.0 113.0 113.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:130.00 stddev:22.13 (17.0%) stddev-sans-first:21.98 03:40:53 INFO - PROCESS | 2312 | Values: 151.0 120.0 140.0 175.0 115.0 113.0 114.0 110.0 142.0 109.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.41 (0.8%) stddev-sans-first:1.30 03:40:53 INFO - PROCESS | 2312 | Values: 181.0 182.0 184.0 185.0 184.0 184.0 181.0 183.0 182.0 184.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.00 stddev:13.21 (22.0%) stddev-sans-first:12.62 03:40:53 INFO - PROCESS | 2312 | Values: 83.0 59.0 59.0 89.0 61.0 58.0 58.0 58.0 85.0 57.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:10.70 (13.7%) stddev-sans-first:11.02 03:40:53 INFO - PROCESS | 2312 | Values: 85.0 71.0 92.0 70.0 69.0 69.0 92.0 69.0 70.0 91.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:88.50 stddev:10.55 (11.9%) stddev-sans-first:11.10 03:40:53 INFO - PROCESS | 2312 | Values: 88.0 86.0 89.0 86.0 98.0 120.0 90.0 86.0 89.0 86.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:94.00 stddev:17.70 (18.8%) stddev-sans-first:18.43 03:40:53 INFO - PROCESS | 2312 | Values: 93.0 95.0 94.0 94.0 93.0 107.0 149.0 113.0 94.0 94.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#7] imaging-desaturate Cycles:10 Average:81.70 Median:82.00 stddev:2.11 (2.6%) stddev-sans-first:2.24 03:40:53 INFO - PROCESS | 2312 | Values: 82.0 81.0 79.0 82.0 83.0 80.0 85.0 85.0 80.0 80.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.71 (3.1%) stddev-sans-first:1.30 03:40:53 INFO - PROCESS | 2312 | Values: 58.0 56.0 56.0 55.0 53.0 54.0 53.0 55.0 53.0 53.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.50 stddev:4.61 (6.4%) stddev-sans-first:3.94 03:40:53 INFO - PROCESS | 2312 | Values: 64.0 76.0 81.0 72.0 72.0 69.0 68.0 73.0 70.0 73.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.00 stddev:2.75 (3.1%) stddev-sans-first:2.79 03:40:53 INFO - PROCESS | 2312 | Values: 85.0 90.0 86.0 89.0 90.0 84.0 85.0 85.0 92.0 87.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:152.00 stddev:12.99 (8.5%) stddev-sans-first:13.76 03:40:53 INFO - PROCESS | 2312 | Values: 155.0 152.0 152.0 147.0 147.0 143.0 147.0 152.0 189.0 150.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.00 stddev:2.69 (1.6%) stddev-sans-first:2.82 03:40:53 INFO - PROCESS | 2312 | Values: 174.0 171.0 171.0 170.0 179.0 174.0 172.0 171.0 172.0 175.0 03:40:53 INFO - PROCESS | 2312 | 03:40:53 INFO - PROCESS | 2312 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:5.65 (8.3%) stddev-sans-first:5.98 03:40:53 INFO - PROCESS | 2312 | Values: 67.0 68.0 68.0 68.0 66.0 65.0 60.0 65.0 70.0 82.0 03:40:53 INFO - PROCESS | 2312 | -------- Summary: end -------- 03:40:53 INFO - PROCESS | 2312 | 03:40:54 INFO - TEST-INFO | 2312: exit 0 03:40:54 INFO - TEST-OK | kraken | took 59402ms 03:40:54 INFO - SUITE-END | took 1178s 03:40:55 INFO - Completed test suite (00:19:38) 03:40:55 INFO - javascript benchmark 03:40:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2835.851480150539, 3228.4673028950224, 3247.6752324767526, 3256.413845507809, 3270.9345813083746, 5432.049391356507, 5480.095784195608, 5546.278983732119, 5579.804706835256, 5620.803271885479, 4697.812087516495, 5525.198846167314, 5546.500814926656, 5633.042382794924, 5637.915431268524, 4994.400671919375, 5010.0, 5057.216131499624, 5087.033463641897, 5091.898162036757, 4474.731516109044, 5064.366954130734, 5184.300119483878, 5190.351206099238, 5242.475752424746, 5034.244863270539, 5144.588432925357, 5172.27588137661, 5180.326557547513, 5271.235670827747, 5044.9747751261375, 5112.079825631385, 5117.48825117489, 5126.53861152498, 5129.461406552296, 5024.0, 5072.365954255718, 5073.568746656539, 5091.0, 5092.668976516515, 5524.06090964533, 5526.364468086141, 5550.5282051025715, 5551.0, 5556.222128901957, 6025.0359942409, 6029.698515074228, 6032.66820324882, 6034.426729460694, 6036.788712395045, 4812.687175333592, 6090.725917333731, 6095.952232173858, 6103.4588915260565, 6124.472479235446, 5575.358833734092, 5595.468430499096, 5602.1036634139155, 5640.774369025234, 5652.095664693629, 6202.069689546604, 6206.100115483229, 6218.9378106218355, 6222.937770622327, 6229.875402491924, 5980.581359304894, 5984.73068711909, 6001.399860013964, 6014.9699251504335, 6034.6982650867285, 6008.098785182257, 6029.78895738647, 6046.516278697693, 6056.36408177135, 6096.817095487142, 6019.067044608005, 6023.48800351975, 6041.516678665696, 6053.45518903292, 6080.452759251689, 5550.47270509301, 5621.353544342372, 6018.879622407615, 6033.5173186145, 6038.064100064497, 4661.160991021648, 4704.247320428715, 4726.172919739032, 4731.905361892743, 4785.641076919175, 4246.299360605527, 4269.380939763717, 4309.245881970643, 4322.092360604245, 4349.499807522176, 5578.497935185853, 5585.58108141891, 5592.720363981784, 5602.327720673546, 5608.691521966331, 6094.116353128771, 6139.386061393825, 6144.631322120687, 6146.692665366714, 6147.938520614736, 5163.328767260232, 5429.348478182565, 5527.889442211133, 5569.805056823073, 5611.7474713637985, 4781.378420805273, 5259.474052594786, 5261.684298942076, 5277.287566178594, 5277.736113194402, 6263.342349053375, 6269.028300613412, 6275.96862015687, 6294.937050629526, 6302.275238347465, 6041.577089603686, 6053.273607166992, 6065.3934606539, 6127.724252408563, 6152.477039451696, 6105.967308275296, 6113.067876544698, 6115.960286751174, 6126.059675755388, 6145.432914606926, 6034.125051867542, 6055.969720151371, 6058.242719660043, 6087.360827113266, 6115.480184184395, 5973.850653733692, 6034.607750496204, 6057.273127224761, 6059.030555111161, 6081.787137450168, 5701.800436984686, 5768.394318596487, 5840.970795145997, 5905.734241959036, 5917.408259174048, 5803.9419605803405, 5911.083782013795, 5939.287285525765, 5949.25634295713, 5961.314448838439, 5760.798372057126, 5765.077587585966, 5773.336066352424, 5774.769009239583, 5779.68211748341, 5746.741396637078, 5785.247917770663, 5899.4985426239255, 5934.169216309807, 5954.404559544011, 5635.746391412478, 5684.9431505684415, 5716.114002329646, 5728.914066289092, 5730.71346432685], "value": 5526.009886781412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.9540148284428, 470.71286515225717, 497.8182963218424, 507.13027158423296, 524.824183898394, 29015.274618134557, 31988.36023279539, 31991.040268791974, 31991.68008319916, 32373.51439728406, 41302.0, 42215.577844221625, 42261.57738422615, 44377.55622443774, 44392.55607443924, 9540.90459095409, 9698.418094914328, 9701.029897010312, 9707.70876873695, 9716.125548700615, 9289.117533834275, 9408.81182376352, 9411.576479058425, 9412.952935235315, 9425.905740942606, 9400.764980875465, 9429.198518125968, 9430.387024843398, 9440.527973601327, 9467.573959171854, 9347.95326023369, 9405.952970235174, 9412.57643406045, 9418.19945304643, 9420.811583768354, 9362.672306469241, 9438.056194380575, 9453.33826632136, 9455.66905158323, 9459.054094590554, 30527.542086868714, 38202.42696359557, 38452.42321365182, 38560.0, 38591.61408385908, 3302.884399046022, 3366.0, 3433.626549380243, 3499.475078738184, 3735.4396840474146, 28192.295192620142, 29348.0, 29388.412231755246, 29433.0, 29463.558046629318, 3183.4747266700965, 3246.9025929221925, 3265.4775235962134, 3317.5189597508465, 3352.2960178362573, 468051.0, 468056.0, 468724.0, 471055.0, 471709.0, 59479.0, 59751.701241493516, 59823.0, 59853.700731496065, 59929.0, 63049.68475157595, 63144.68427657832, 63148.36851631517, 63325.68337158377, 63382.68308658427, 16245.187740612922, 16448.83551164497, 16462.0, 16503.587410314598, 16528.00831950087, 12783.93608031954, 12874.485020599072, 12885.613431597067, 12891.162074465317, 12905.419256133497, 16471.588210294838, 16740.41408550695, 16782.328706851833, 16785.412510562073, 16790.412335568442, 6596.967015164893, 6626.9065604174775, 6791.626460544619, 6844.349786770249, 7236.819079523054, 858.3948316436897, 908.6229214875884, 973.7419583810296, 998.5406712912142, 1046.4349251404308, 25152.37119072038, 29221.0, 29685.703142968294, 30346.0, 31695.52456713151, 1047.7537778622018, 1069.6844430892904, 1073.6993641780157, 1090.334895713614, 1095.8794532601407, 14339.52085040059, 14732.042417242845, 15345.772338212917, 15631.84368156304, 15773.526794196192, 582680.0, 587386.0, 590573.0, 591815.0, 594572.0, 56851.75649530795, 57108.42891571031, 57183.428165718644, 57186.0, 57298.42701572931, 1263.4567136131368, 1307.7842156044337, 1310.9934450327687, 1349.912255703376, 1397.8741913227655, 28868.422631546833, 29101.0, 29411.0, 29857.25356866096, 30038.399232015676, 48876.2668559979, 49339.50660493349, 49390.320113187445, 49445.25832112593, 49510.51468455952, 475858.62070689426, 476036.0, 476927.61536192097, 478493.0, 479389.0, 33961.66038339684, 34881.825590871886, 35121.297574048884, 39081.80459097686, 39539.011524712114, 42081.57918420899, 42264.78867605642, 42382.57617423786, 43116.568834311256, 43957.0, 31319.373612527164, 35994.92015239547, 38393.23213535769, 39000.6099938997, 40159.59840401675, 37839.24321513609, 38164.61835381722, 38288.873780195034, 39379.80310098431, 39730.40404393994, 31493.0, 34838.505194972255, 37955.43066853944, 38157.80921095488, 38200.61799381971, 29757.851210743807, 31810.363792723554, 33924.66075339215, 34140.97577072692, 34166.0, 25021.74978250194, 25291.367715806515, 25338.0, 25348.619770703823, 26010.73989260083], "value": 19443.50294912197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.233875353627, 369.7282497364437, 371.9776813391195, 388.35339160298105, 399.8900302416835, 18978.0, 20186.59626807465, 20475.897620511914, 20721.0, 20925.686114708293, 20550.69173962392, 20942.057607407663, 21103.472413189676, 21397.358079257647, 21666.783332166673, 5585.972070139644, 5873.177755114288, 7303.8539229215385, 7676.577788221645, 7803.258690424429, 4548.363229147905, 4771.475137734864, 7652.617369131549, 7801.843963120734, 7817.140114587391, 4811.109944660231, 7756.728514501993, 7780.0, 7842.568658723768, 7857.292843644071, 7453.291937265951, 7702.075750909927, 7750.263724946121, 7759.30166285037, 7840.764777056669, 7336.193018767931, 7407.701153983422, 7474.327310542078, 7497.962510187469, 7499.362554182901, 28033.57949630757, 29324.120276391743, 29407.85296073506, 29444.70555294441, 29814.254643633867, 2261.0729600863565, 2320.8259380546356, 2394.3535245483795, 2686.2209959111833, 2912.184588315275, 13413.786725190332, 16686.33254669812, 16888.662226755518, 17438.0, 17631.735523967152, 1944.4652720501833, 1959.6080783843147, 1988.2643421933833, 2002.1690998235715, 2030.157484643871, 76438.61780691116, 130659.34670326683, 130933.34533327368, 134815.0, 135826.96259556114, 16906.492805215865, 19637.60724785496, 20779.79220207809, 20969.161233550793, 21146.894265528575, 34650.13374665654, 34707.82646086753, 34727.65272347244, 34858.825705871306, 34863.30273394518, 10672.94663526693, 10827.0, 11005.559777608805, 11029.062529685067, 11180.161487888441, 9009.504477253813, 9039.45763254207, 9047.954760226157, 9218.907810921804, 9248.630054797868, 9511.476868772284, 9563.234941204686, 9584.616615335448, 9690.854637180448, 9732.90267097334, 2894.3487715263896, 2900.1583994163434, 3128.5463607776746, 3619.94570081449, 3699.2231631357176, 852.6887685994567, 930.6184464369711, 962.4610218277787, 974.8001659659752, 1041.9635312764017, 19759.308424205083, 21408.250711225344, 21543.030563624674, 21569.558027886244, 21646.350609481742, 927.76871623745, 954.6515521834481, 975.3416443900339, 991.7669347703284, 991.9008099190023, 8752.8687069694, 11019.779604407868, 11083.612073577548, 11167.168186195968, 11268.943655281671, 563063.0, 568715.0, 568787.0, 578673.0, 582336.0, 98513.50743246381, 134035.0, 138891.0, 139734.3013284927, 139791.30104349414, 1005.527402121002, 1032.8708911386077, 1061.686802393296, 1066.195022757813, 1070.2936062199067, 18231.179596917904, 18391.0, 18668.7199692002, 18682.906585466986, 18836.058197090366, 52618.73690631522, 53799.46200537944, 54402.911941760154, 54802.72598636981, 55829.0, 80520.59739701264, 82487.5875620642, 83380.58309708412, 84832.0, 86479.0, 5492.670439773467, 6655.56738811995, 6979.581225126509, 7407.111146662435, 7522.435817313833, 7283.308085731847, 10449.477526123512, 10612.469376531297, 12836.844683978487, 13430.93284533571, 11029.283096598696, 11142.997130258316, 11233.213675042669, 11661.475233614678, 11803.409829508662, 11063.114950804078, 11066.39134847591, 11114.444277785917, 11514.712132196428, 11521.366324851879, 10910.890891090987, 11324.980751732384, 11454.0, 11801.350925698825, 11880.287182769061, 6896.344847239504, 7663.961680191564, 7988.680452781824, 8187.79530511742, 8306.916930830856, 9467.621295148117, 10468.214883883587, 10752.301100428447, 11142.610008649659, 11360.715982100182], "value": 10915.567288307418, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.0243341827806, 1523.87808975282, 1748.9737653935192, 1849.6855534559124, 1931.4205738278513, 8423.789405264872, 8779.824403511926, 8795.472271663688, 8855.95572022139, 8871.713601527783, 7401.962990185055, 8102.5543595102245, 8124.837503249932, 8521.616527256272, 8746.475211487315, 2313.618252988259, 2322.7677232276724, 2354.3290162303747, 2361.3388251289666, 2363.456405026845, 2333.2416964486524, 2335.7664233576593, 2383.344580240439, 2383.916562920298, 2384.2132096408145, 2359.6932398788217, 2368.7157541095007, 2381.1784934197763, 2385.5705972924866, 2388.22382725614, 2335.089315167086, 2356.0222507659387, 2362.9291121266388, 2367.656689779972, 2370.8221883358815, 2332.207049603126, 2346.0263990443946, 2350.2361732437025, 2358.0, 2362.7637236276405, 8636.481811091355, 8744.69393571222, 8757.56212189388, 8783.956080219557, 8790.956045219797, 5121.0, 5289.418164001957, 5292.285541451932, 5294.470552944675, 5301.761420736076, 3518.0852978225844, 3518.612952575215, 3586.964130358715, 3594.8741794037082, 3596.29872174926, 1044.2116202267316, 1049.4018409506584, 1050.2123407444417, 1064.3454275620438, 1075.0861767497643, 11272.492737826817, 11359.261647992937, 11369.602063927805, 11378.71553211168, 11382.146339024606, 4429.68992170545, 4521.570450807168, 4720.693154944917, 4733.928991065137, 4746.454157771831, 9639.325247232766, 9653.662121825842, 9656.130948214695, 9661.275404344702, 9689.321747477608, 5252.65857719247, 5279.44565820591, 5287.391949925808, 5295.126304159848, 5303.496167864047, 4290.721103128349, 4398.3402489626815, 4526.479454862733, 4540.228161212536, 4840.564349208588, 3807.3337165996395, 3836.1176929305857, 3908.804559772, 3929.528456585228, 3994.7003974702016, 1075.8224892892586, 1246.7506498700295, 1264.3741348032784, 1276.2087505746497, 1281.1992504684572, 819.5328662662345, 891.4517571693361, 895.5387975192781, 913.7624217703445, 955.3790036476346, 5693.231413759172, 5813.302403711582, 5941.910871336933, 5988.520918326523, 6119.204503414617, 436.09727863322615, 439.3519558651028, 443.7537166872417, 917.5779141594808, 1003.2124782046059, 3418.931621367559, 3909.2963266612282, 4051.311277082903, 4053.3786485405485, 4057.2494088593703, 9820.901790982141, 10626.309289896286, 10721.249512534203, 10722.678319650267, 10754.623588174376, 10259.281850270409, 10472.528736206888, 10696.946515267375, 10698.62554810578, 10735.087517560938, 8094.676212951534, 8248.917510824935, 8253.752387428387, 8262.504249744914, 8263.586820659064, 5816.1857339973285, 5816.272965879265, 5893.44012318829, 5960.314563825215, 5966.582339236212, 9529.285303602397, 10022.949885250528, 10063.446510441996, 10069.647562335284, 10072.395656260354, 8657.437266577652, 10160.898391015995, 10222.182225422097, 10231.539580718736, 10283.537240824031, 6547.181602299713, 6770.424513916372, 6935.549189302679, 7060.0, 7079.681414336264, 7942.324902383131, 8120.634571444417, 8263.628136733982, 8435.704750333709, 8450.366222533212, 6511.446527045254, 6750.932490675227, 7355.8161045974275, 7434.8884766729625, 7548.320651141424, 6924.238333783179, 7345.963270183614, 7353.080864891888, 7353.191148973501, 7409.2590740927635, 6451.41937225652, 6878.0, 6957.095577574883, 7027.859442811217, 7032.683529241299, 4821.927671084866, 5010.473900240422, 5042.823501177441, 5145.819896303611, 5225.902560462346, 5015.1975683890405, 5072.340595722532, 5089.745512724423, 5125.7693403796175, 5291.973540132275], "value": 4502.2747250338725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.61821486711875, 307.9230192451887, 311.752157035157, 312.9186411533002, 318.30132858375873, 4655.394798676176, 5317.760700768465, 5376.8118115866, 5404.513593776559, 5409.810656627019, 4600.539946005398, 4837.177679794435, 5336.849939808085, 5359.758810853511, 5363.570914326854, 3730.8321125549414, 3981.800909954505, 4003.619656132663, 4059.776712280823, 4090.2637525245445, 3436.79712100764, 4068.5524592294823, 4100.851761506783, 4117.505899292089, 4119.7454011331165, 3399.9660003399895, 3722.5532936047575, 3796.354619714641, 4063.4108054332105, 4084.9591504085024, 2891.580720795494, 3489.6041583366605, 4059.857904973341, 4065.5934406559404, 4066.1664358806374, 2719.564869620871, 2973.04862444018, 2977.0, 3490.3019396120876, 3493.68556829884, 4220.556841531657, 5298.278059630636, 5316.867078323073, 5321.574274058066, 5321.73391330432, 4427.2695005324085, 4516.209663308908, 4637.234856248715, 4641.537915556589, 4658.34783130362, 5112.923306150411, 5624.621967617919, 5654.830355089354, 5657.23627310312, 5670.432956704338, 3254.8209848458328, 3823.5794062653085, 3823.9617603823885, 3842.634949679776, 3848.903777405559, 4623.884402889921, 4651.86044418668, 4937.9755499267985, 5122.463261021679, 5895.498882594985, 3199.376121656255, 3377.902181790928, 3770.472133901256, 3879.739084797452, 3901.239258344652, 3539.8672424824326, 3791.829367678461, 3968.8809335719975, 3973.145773658677, 3977.0, 4115.444415003936, 4394.725529596381, 4637.006087382458, 4638.350630911675, 4652.55800698933, 5547.223388725582, 5669.270872383962, 5720.599558030898, 5733.3406658234, 5781.595288329777, 1197.7305106351, 1210.5823490895627, 1243.6382161533106, 1334.3861823561076, 1339.4173534512324, 791.782259878538, 811.6266517401943, 831.7920519870032, 831.9292860106838, 857.2927334948693, 4206.684498662613, 5111.284420181178, 5152.3044389007, 5271.288376069258, 5298.470152984671, 4958.206686930146, 5861.736221870026, 5861.798331342065, 5892.587518873637, 5902.8819423611285, 4644.3265726470145, 4735.289706544046, 5090.567301779316, 5337.893242135136, 5344.839654810362, 2920.8831646733893, 3041.285297954979, 3060.908172754821, 3064.0195137556216, 3067.1169101044948, 4612.76936153191, 5273.894522109613, 5443.516149664218, 5524.558035357162, 6035.486983606354, 5699.696728969684, 5704.258446401941, 5915.674637894957, 5952.065578686944, 5974.820755377345, 4552.908941821212, 5072.923906141411, 5400.567954563626, 5655.349634791969, 6011.759529618854, 5119.87200319995, 5498.862528436661, 5814.622049566765, 5827.854303642443, 5857.882842343214, 4789.185838407409, 5705.201271822031, 5723.8804851356945, 5746.310442746897, 5747.396523364986, 4953.876153096201, 5025.748712564284, 5980.700964951648, 5987.6169963970215, 6126.969365153146, 3576.535050443426, 3800.486934263839, 3805.4482100095884, 3847.883214596186, 3921.31377009028, 5097.898042039213, 5654.40628733977, 5812.593118481665, 5856.648601083949, 5860.38465961085, 5240.528352448146, 5730.885382292247, 5742.885142297214, 5748.712564371848, 5750.562359410147, 4596.954030459653, 5468.343798744176, 5578.832635020955, 5635.8591035224445, 5641.0, 4451.888702782457, 5537.501624853782, 5548.250986116984, 5561.6663000220115, 5587.357453892854, 5369.758360873692, 5398.757055932414, 5562.805301814417, 5579.40557640699, 5767.653940763567, 4390.670699697599, 5528.806491772769, 5564.0, 5572.85756419941, 5574.832755017356], "value": 4041.8876528920214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.8729722298597, 732.4543215304598, 742.2985278911427, 814.2671595563995, 825.8190787174342, 15831.762523562142, 15884.046957182578, 16050.0, 16093.597660058504, 16126.193690315482, 11422.0, 14030.228337441436, 14085.295735213249, 14444.350004249807, 14467.204303763288, 712.3588770106893, 730.5178582135792, 738.269113577557, 739.548875186136, 880.9823803523925, 726.9382102521292, 731.3162193349209, 875.4747151708988, 907.015887761776, 907.9954600227022, 955.8996305387939, 965.5510187762688, 971.028971028971, 972.6255391674185, 978.4765150644407, 968.7578105473632, 969.6751588217955, 969.8011907558933, 970.4710932541747, 972.4311277902435, 718.4575645387752, 718.9856202875966, 719.7696737044148, 719.8272414620506, 724.221461928429, 11882.821757673642, 12405.317707526083, 14596.34316455762, 15189.544313670607, 15195.240237988168, 13862.722745545134, 14690.338934747962, 14743.410263589443, 14893.702125957529, 14920.70158596822, 11698.47356868943, 11701.2979221247, 11741.823872641917, 11786.528538858436, 12248.571300004547, 3797.35444974355, 3818.2554401891643, 3862.4592557042038, 3962.6829853611644, 3976.1451287973223, 119014.0, 119394.0, 119749.80250197473, 119826.0, 120550.0, 9831.45926974009, 10609.045185933302, 11578.421078946187, 11698.590549330733, 11976.401179941142, 21363.718176909435, 23914.40213994664, 24389.390265243157, 24400.90195941187, 24424.0, 7043.295670432915, 7797.259260370256, 8045.517268963881, 8087.83824323522, 8119.878201826978, 4376.606105450524, 4386.912261754811, 4435.756033418128, 4445.888852778642, 4578.587927086578, 3617.2584620152807, 3723.106454450912, 3775.9433608495897, 3781.905452363713, 3787.0721673189696, 667.5222092896066, 672.525869262169, 673.7204060315013, 681.1485642946317, 685.9759908403182, 625.43710660406, 666.5567397680585, 675.1796567170812, 675.4798804920184, 678.3860606151441, 7933.9603301983125, 9075.863862042075, 9539.57071931751, 9608.807823843625, 9619.759506012266, 554.0829926471696, 644.7839973608893, 671.1174805131237, 681.3493114076045, 683.8598087392073, 4129.504459464883, 4254.574542545783, 4260.4248426462655, 4591.380163677927, 4595.839145629887, 92909.5354523223, 95154.0, 96164.0, 117105.3881497717, 117851.41074294574, 120298.0, 124571.0, 129987.0, 130170.0, 135852.64147358597, 111936.0, 112557.74884502264, 114151.0, 115669.4216528912, 119103.40448297876, 7425.740099096506, 8024.675928471736, 8268.2558569729, 8863.556822158736, 9175.403598766059, 62740.37259627528, 63571.68214158899, 63624.363756363695, 64247.0, 66476.00285995618, 61172.69413652903, 65888.0, 77621.61189194018, 93935.53032234796, 95516.52241738747, 12851.807222891477, 12992.350382480648, 13145.816876481163, 13277.800832987707, 13369.933150334185, 14681.706365872837, 14771.261436927896, 14832.406703731729, 14964.476243331432, 15652.295646695698, 13761.243131627456, 13852.307384630527, 14392.280385980868, 14521.273936303354, 14527.564173074825, 13523.526676566273, 13845.30773461343, 13918.304084795518, 14182.361793719514, 14410.711785764435, 13311.267880266678, 13489.436252736026, 13537.0, 13672.931635342158, 13731.862681373457, 10153.0, 10154.644587439405, 10823.404712740874, 11246.493907774333, 11662.475188616363, 11179.720506987065, 11246.437678116225, 11524.769504610029, 11541.36522491271, 11554.711132221426], "value": 7808.090896606203, "unit": "score"}], "name": "dromaeo_css", "value": 7418.346078985585, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 114.0, 113.0, 112.0, 113.0, 112.0, 114.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 120.0, 140.0, 175.0, 115.0, 113.0, 114.0, 110.0, 142.0, 109.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 184.0, 185.0, 184.0, 184.0, 181.0, 183.0, 182.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 61.0, 58.0, 58.0, 58.0, 85.0, 57.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 92.0, 70.0, 69.0, 69.0, 92.0, 69.0, 70.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 98.0, 120.0, 90.0, 86.0, 89.0, 86.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 93.0, 107.0, 149.0, 113.0, 94.0, 94.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 79.0, 82.0, 83.0, 80.0, 85.0, 85.0, 80.0, 80.0], "value": 81.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 55.0, 53.0, 54.0, 53.0, 55.0, 53.0, 53.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 81.0, 72.0, 72.0, 69.0, 68.0, 73.0, 70.0, 73.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 86.0, 89.0, 90.0, 84.0, 85.0, 85.0, 92.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 152.0, 147.0, 147.0, 143.0, 147.0, 152.0, 189.0, 150.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 171.0, 170.0, 179.0, 174.0, 172.0, 171.0, 172.0, 175.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 66.0, 65.0, 60.0, 65.0, 70.0, 82.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1453.7000000000003, "alertThreshold": 2.0}]} 03:40:55 INFO - Return code: 0 03:40:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:40:55 INFO - # TBPL SUCCESS # 03:40:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:40:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:40:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:40:55 INFO - 2016-10-15 03:40:55.232 screenresolution[2316:26798] starting screenresolution argv=screenresolution get 03:40:55 INFO - 2016-10-15 03:40:55.236 screenresolution[2316:26798] Display 0: 1600x1200x32@60 03:40:55 INFO - 2016-10-15 03:40:55.241 screenresolution[2317:26801] starting screenresolution argv=screenresolution list 03:40:55 INFO - Available Modes on Display 0 03:40:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:40:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:40:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:40:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:40:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:40:55 INFO - Intel Iris: 03:40:55 INFO - Chipset Model: Intel Iris 03:40:55 INFO - Type: GPU 03:40:55 INFO - Bus: Built-In 03:40:55 INFO - VRAM (Dynamic, Max): 1536 MB 03:40:55 INFO - Vendor: Intel (0x8086) 03:40:55 INFO - Device ID: 0x0a2e 03:40:55 INFO - Revision ID: 0x0009 03:40:55 INFO - Displays: 03:40:55 INFO - Display: 03:40:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:40:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:40:55 INFO - Display Serial Number: 1600x1200 60 03:40:55 INFO - Main Display: Yes 03:40:55 INFO - Mirror: Off 03:40:55 INFO - Online: Yes 03:40:55 INFO - Rotation: Supported 03:40:55 INFO - Return code: 0 03:40:55 INFO - Running post-action listener: _resource_record_post_action 03:40:55 INFO - [mozharness: 2016-10-15 10:40:55.481049Z] Finished run-tests step (success) 03:40:55 INFO - Running post-run listener: _resource_record_post_run 03:40:55 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 188416; Write bytes: 712233984; Read time: 4; Write time: 2075 03:40:55 INFO - TinderboxPrint: CPU usage
53.6% 03:40:55 INFO - TinderboxPrint: I/O read bytes / time
188,416 / 4 03:40:55 INFO - TinderboxPrint: I/O write bytes / time
712,233,984 / 2,075 03:40:55 INFO - TinderboxPrint: CPU idle
2,252.2 (46.4%) 03:40:55 INFO - TinderboxPrint: CPU system
974.8 (20.1%) 03:40:55 INFO - TinderboxPrint: CPU user
1,626.7 (33.5%) 03:40:55 INFO - TinderboxPrint: Swap in / out
407,769,088 / 0 03:40:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405326336; Write bytes: 399591424; Read time: 29715; Write time: 771 03:40:55 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 151552; Write bytes: 300256256; Read time: 3; Write time: 1269 03:40:55 INFO - Running post-run listener: _upload_blobber_files 03:40:55 INFO - Blob upload gear active. 03:40:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:40:55 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 03:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:40:56 INFO - (blobuploader) - INFO - Open directory for files ... 03:40:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:40:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:40:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:56 INFO - (blobuploader) - INFO - Done attempting. 03:40:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:40:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:40:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:57 INFO - (blobuploader) - INFO - Done attempting. 03:40:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:40:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:41:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:00 INFO - (blobuploader) - INFO - Done attempting. 03:41:00 INFO - (blobuploader) - INFO - Iteration through files over. 03:41:00 INFO - Return code: 0 03:41:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:41:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:41:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17be54edd9f6d4cfa7a6baf8d26cb2e2210b3fdb0adfef1d0b418dff9e7e96b343748c5ba66dcaad93affa91e2fb3e38e85320aac56896761cecb4a0440531ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/afba2411400eb34e6b471c8bf6a66b5e83b2847ccfbcfb48cb7bd131bd7c73ce9c1648b2031a894aee07c16747a4775a8ab329871228577a5a9b569fe9d2fe9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32e989e99a2c1c70aa417ba2a8f17bdad46665211fee8ed5c0e67fd9443ae331ea9b58c75e8a5d93dc92805813f1f1e200c9c9f256f17cb12e1a44007e973377"} 03:41:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:41:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:41:00 INFO - Contents: 03:41:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17be54edd9f6d4cfa7a6baf8d26cb2e2210b3fdb0adfef1d0b418dff9e7e96b343748c5ba66dcaad93affa91e2fb3e38e85320aac56896761cecb4a0440531ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/afba2411400eb34e6b471c8bf6a66b5e83b2847ccfbcfb48cb7bd131bd7c73ce9c1648b2031a894aee07c16747a4775a8ab329871228577a5a9b569fe9d2fe9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32e989e99a2c1c70aa417ba2a8f17bdad46665211fee8ed5c0e67fd9443ae331ea9b58c75e8a5d93dc92805813f1f1e200c9c9f256f17cb12e1a44007e973377"} 03:41:00 INFO - Running post-run listener: copy_logs_to_upload_dir 03:41:00 INFO - Copying logs to upload dir... 03:41:00 INFO - mkdir: /builds/slave/test/build/upload/logs 03:41:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.644453 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-15 03:41:00.750523) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 03:41:00.754153) ========= 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.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17be54edd9f6d4cfa7a6baf8d26cb2e2210b3fdb0adfef1d0b418dff9e7e96b343748c5ba66dcaad93affa91e2fb3e38e85320aac56896761cecb4a0440531ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/afba2411400eb34e6b471c8bf6a66b5e83b2847ccfbcfb48cb7bd131bd7c73ce9c1648b2031a894aee07c16747a4775a8ab329871228577a5a9b569fe9d2fe9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32e989e99a2c1c70aa417ba2a8f17bdad46665211fee8ed5c0e67fd9443ae331ea9b58c75e8a5d93dc92805813f1f1e200c9c9f256f17cb12e1a44007e973377"} build_url:https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/Ko4wbg7ERLKrC1WTdr0lqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17be54edd9f6d4cfa7a6baf8d26cb2e2210b3fdb0adfef1d0b418dff9e7e96b343748c5ba66dcaad93affa91e2fb3e38e85320aac56896761cecb4a0440531ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/afba2411400eb34e6b471c8bf6a66b5e83b2847ccfbcfb48cb7bd131bd7c73ce9c1648b2031a894aee07c16747a4775a8ab329871228577a5a9b569fe9d2fe9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32e989e99a2c1c70aa417ba2a8f17bdad46665211fee8ed5c0e67fd9443ae331ea9b58c75e8a5d93dc92805813f1f1e200c9c9f256f17cb12e1a44007e973377"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 03:41:00.785968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:41:00.786280) ========= 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.ixngYvuI9h/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.HNnVNsiixD/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005321 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:41:00.834746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 03:41:00.835070) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 03:41:00.835380) ========= ========= Total master_lag: 0.18 =========