builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1476627596.29 results: success (0) buildid: 20161016062235 builduid: 4a39fe3ef9ba4cb7a85ba236bfc5627a revision: 9079d167112122805f99f57bb8856e1b1675af0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.289828) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.290248) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.290692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.508772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.509300) ========= 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.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-16 07:19:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 55.6M=0s 2016-10-16 07:19:56 (55.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114603 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.665364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.665686) ========= 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.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.025968 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:56.733827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 07:19:56.734122) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9079d167112122805f99f57bb8856e1b1675af0f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9079d167112122805f99f57bb8856e1b1675af0f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-16 07:19:56,816 truncating revision to first 12 chars 2016-10-16 07:19:56,816 Setting DEBUG logging. 2016-10-16 07:19:56,816 attempt 1/10 2016-10-16 07:19:56,816 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9079d1671121?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 07:19:57,947 unpacking tar archive at: mozilla-central-9079d1671121/testing/mozharness/ program finished with exit code 0 elapsedTime=1.401234 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 07:19:58.161871) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:58.162524) ========= script_repo_revision: 9079d167112122805f99f57bb8856e1b1675af0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:58.163355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:58.163915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 07:19:58.186636) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-16 07:19:58.186928) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:19:58 INFO - MultiFileLogger online at 20161016 07:19:58 in /builds/slave/test 07:19:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:19:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:58 INFO - {'append_to_log': False, 07:19:58 INFO - 'base_work_dir': '/builds/slave/test', 07:19:58 INFO - 'blob_upload_branch': 'Firefox', 07:19:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:58 INFO - 'branch': 'Firefox', 07:19:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:58 INFO - 'config_files': ('talos/mac_config.py',), 07:19:58 INFO - 'default_actions': ('clobber', 07:19:58 INFO - 'read-buildbot-config', 07:19:58 INFO - 'download-and-extract', 07:19:58 INFO - 'populate-webroot', 07:19:58 INFO - 'create-virtualenv', 07:19:58 INFO - 'install', 07:19:58 INFO - 'run-tests'), 07:19:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:58 INFO - 'download_minidump_stackwalk': True, 07:19:58 INFO - 'download_symbols': 'ondemand', 07:19:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:58 INFO - 'installer_path': 'installer.exe', 07:19:58 INFO - 'log_level': 'info', 07:19:58 INFO - 'log_name': 'talos', 07:19:58 INFO - 'log_to_console': True, 07:19:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:19:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:19:58 INFO - 'opt_config_files': (), 07:19:58 INFO - 'pip_index': False, 07:19:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:19:58 INFO - 'cmd': ('bash', 07:19:58 INFO - '-c', 07:19:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:19:58 INFO - 'enabled': True, 07:19:58 INFO - 'halt_on_failure': False, 07:19:58 INFO - 'name': 'check_screen_resolution'},), 07:19:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:19:58 INFO - 'cmd': ('bash', 07:19:58 INFO - '-c', 07:19:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:19:58 INFO - 'enabled': True, 07:19:58 INFO - 'halt_on_failure': False, 07:19:58 INFO - 'name': 'check_screen_resolution'},), 07:19:58 INFO - 'run_cmd_checks_enabled': True, 07:19:58 INFO - 'sps_profile': False, 07:19:58 INFO - 'sps_profile_interval': 0, 07:19:58 INFO - 'suite': 'dromaeojs-e10s', 07:19:58 INFO - 'system_bits': '32', 07:19:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:58 INFO - 'title': 't-yosemite-r7-0353', 07:19:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:58 INFO - 'use_talos_json': True, 07:19:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:58 INFO - 'work_dir': 'build'} 07:19:58 INFO - [mozharness: 2016-10-16 14:19:58.384749Z] Running clobber step. 07:19:58 INFO - Running pre-action listener: _resource_record_pre_action 07:19:58 INFO - Running main action method: clobber 07:19:58 INFO - rmtree: /builds/slave/test/build 07:19:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:20:01 INFO - Running post-action listener: _resource_record_post_action 07:20:01 INFO - [mozharness: 2016-10-16 14:20:01.193426Z] Finished clobber step (success) 07:20:01 INFO - [mozharness: 2016-10-16 14:20:01.193582Z] Running read-buildbot-config step. 07:20:01 INFO - Running pre-action listener: _resource_record_pre_action 07:20:01 INFO - Running main action method: read_buildbot_config 07:20:01 INFO - Using buildbot properties: 07:20:01 INFO - { 07:20:01 INFO - "project": "", 07:20:01 INFO - "product": "firefox", 07:20:01 INFO - "script_repo_revision": "production", 07:20:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:20:01 INFO - "repository": "", 07:20:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:20:01 INFO - "buildid": "20161016062235", 07:20:01 INFO - "pgo_build": "False", 07:20:01 INFO - "basedir": "/builds/slave/test", 07:20:01 INFO - "buildnumber": 48, 07:20:01 INFO - "slavename": "t-yosemite-r7-0353", 07:20:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:20:01 INFO - "platform": "yosemite_r7", 07:20:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:20:01 INFO - "branch": "mozilla-central", 07:20:01 INFO - "repo_path": "mozilla-central", 07:20:01 INFO - "revision": "9079d167112122805f99f57bb8856e1b1675af0f", 07:20:01 INFO - "stage_platform": "macosx64", 07:20:01 INFO - "builduid": "4a39fe3ef9ba4cb7a85ba236bfc5627a", 07:20:01 INFO - "slavebuilddir": "test" 07:20:01 INFO - } 07:20:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:20:01 INFO - Running post-action listener: _resource_record_post_action 07:20:01 INFO - [mozharness: 2016-10-16 14:20:01.197010Z] Finished read-buildbot-config step (success) 07:20:01 INFO - [mozharness: 2016-10-16 14:20:01.197104Z] Running download-and-extract step. 07:20:01 INFO - Running pre-action listener: _resource_record_pre_action 07:20:01 INFO - Running main action method: download_and_extract 07:20:01 INFO - mkdir: /builds/slave/test/build/tests 07:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:01 INFO - trying https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:20:01 INFO - Downloading https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/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 07:20:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/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 07:20:02 INFO - Downloaded 1199 bytes. 07:20:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:20:02 INFO - Using the following test package requirements: 07:20:02 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:20:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:20:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:20:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'jsshell-mac.zip'], 07:20:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:20:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:20:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:20:02 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:20:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:20:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:20:02 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:20:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:20:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:20:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:20:02 INFO - Fetch https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:20:03 INFO - Http code: 200 07:20:03 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 07:20:03 INFO - x-amz-cf-id: dHRzu3uycNnKhZH0uHsC1ZgMJE8-sdZFZuAdwGX0mGvT2yGicYmW6A== 07:20:03 INFO - x-amz-version-id: gJptlDFVRPsWcEjdVuD287SaUcaH5e_X 07:20:03 INFO - Expected file size: 16516731 07:20:03 INFO - Obtained file size: 16516731 07:20:10 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 07:20:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:20:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:20:10 INFO - Fetch https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:20:10 INFO - Http code: 200 07:20:10 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 07:20:10 INFO - x-amz-cf-id: t7qUPGGKHrn4IEcxxS98KSREQRK0Vl52iGrWYOuG1qH1nvZqXoTHJA== 07:20:10 INFO - x-amz-version-id: gJptlDFVRPsWcEjdVuD287SaUcaH5e_X 07:20:11 INFO - Expected file size: 16516731 07:20:11 INFO - Obtained file size: 16516731 07:20:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:20:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:20:17 INFO - Fetch https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:20:18 INFO - Http code: 200 07:20:18 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 07:20:18 INFO - x-amz-cf-id: Qel-tPa8Xu2yRtylr_e9U9Z0NXMB3MwKuTdzWYxLuEN-AztPYJ6J2A== 07:20:18 INFO - x-amz-version-id: EgJ1lP4_CMqQVrotpXq3vfAAteEQKIXD 07:20:19 INFO - Expected file size: 14409028 07:20:19 INFO - Obtained file size: 14409028 07: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')]} 07:20:19 INFO - trying https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:20:19 INFO - Downloading https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:20:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/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 07:20:22 INFO - Downloaded 111962614 bytes. 07:20:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:20:22 INFO - mkdir: /builds/slave/test/properties 07:20:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:20:22 INFO - Writing to file /builds/slave/test/properties/build_url 07:20:22 INFO - Contents: 07:20:22 INFO - build_url:https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:20:22 INFO - Running post-action listener: _resource_record_post_action 07:20:22 INFO - Running post-action listener: set_extra_try_arguments 07:20:22 INFO - [mozharness: 2016-10-16 14:20:22.924540Z] Finished download-and-extract step (success) 07:20:22 INFO - [mozharness: 2016-10-16 14:20:22.924668Z] Running populate-webroot step. 07:20:22 INFO - Running pre-action listener: _resource_record_pre_action 07:20:22 INFO - Running main action method: populate_webroot 07:20:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:20:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:20:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:20:22 INFO - '--tppagecycles', 07:20:22 INFO - '7'], 07:20:22 INFO - 'tests': ['tsvgm']}}, 07:20:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:20:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:20:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:20:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:20:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['damp', 'tps']}, 07:20:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'tests': ['damp', 'tps']}, 07:20:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['dromaeo_dom']}, 07:20:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:20:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['basic_compositor_video']}, 07:20:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:20:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'other-e10s': {'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['a11yr', 07:20:22 INFO - 'ts_paint', 07:20:22 INFO - 'tpaint', 07:20:22 INFO - 'sessionrestore', 07:20:22 INFO - 'sessionrestore_no_auto_restore', 07:20:22 INFO - 'tabpaint']}, 07:20:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['tsvgx', 07:20:22 INFO - 'tsvgr_opacity', 07:20:22 INFO - 'tart', 07:20:22 INFO - 'tscrollx', 07:20:22 INFO - 'cart']}, 07:20:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:20:22 INFO - 'tsvgr_opacity', 07:20:22 INFO - 'tart', 07:20:22 INFO - 'tscrollx', 07:20:22 INFO - 'cart']}, 07:20:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'talos_options': ['--disable-e10s'], 07:20:22 INFO - 'tests': ['tp5o']}, 07:20:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'tests': ['tp5o']}, 07:20:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'talos_options': ['--disable-e10s', 07:20:22 INFO - '--xperf_path', 07:20:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:22 INFO - 'tests': ['tp5n']}, 07:20:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:22 INFO - 'talos_options': ['--xperf_path', 07:20:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:22 INFO - 'tests': ['tp5n']}}, 07:20:22 INFO - 'talos.zip': {'path': '', 07:20:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:20:22 INFO - Running post-action listener: _resource_record_post_action 07:20:22 INFO - [mozharness: 2016-10-16 14:20:22.943404Z] Finished populate-webroot step (success) 07:20:22 INFO - [mozharness: 2016-10-16 14:20:22.943513Z] Running create-virtualenv step. 07:20:22 INFO - Running pre-action listener: _resource_record_pre_action 07:20:22 INFO - Running main action method: create_virtualenv 07:20:22 INFO - Creating virtualenv /builds/slave/test/build/venv 07:20:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:20:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:20:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:20:23 INFO - Using real prefix '/tools/python27' 07:20:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:20:23 INFO - Installing distribute.............................................................................................................................................................................................done. 07:20:26 INFO - Installing pip.................done. 07:20:26 INFO - Return code: 0 07:20:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:20:26 INFO - Reading from file tmpfile_stdout 07:20:26 INFO - Output received: 07:20:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:20:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07: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')]} 07:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07: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')]} 07:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07: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', 'pip>=1.5'] in /builds/slave/test/build 07: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 pip>=1.5 07:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:26 INFO - 'HOME': '/Users/cltbld', 07:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:26 INFO - 'LOGNAME': 'cltbld', 07:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:26 INFO - 'MOZ_NO_REMOTE': '1', 07:20:26 INFO - 'NO_EM_RESTART': '1', 07:20:26 INFO - 'PAGER': '/bin/cat', 07:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:26 INFO - 'PWD': '/builds/slave/test', 07:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:26 INFO - 'SHELL': '/bin/bash', 07:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:26 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:26 INFO - 'USER': 'cltbld', 07:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:26 INFO - 'XPC_FLAGS': '0x0', 07:20:26 INFO - 'XPC_SERVICE_NAME': '0', 07:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:20:27 INFO - Cleaning up... 07:20:27 INFO - Return code: 0 07:20:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:27 INFO - 'HOME': '/Users/cltbld', 07:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:27 INFO - 'LOGNAME': 'cltbld', 07:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:27 INFO - 'MOZ_NO_REMOTE': '1', 07:20:27 INFO - 'NO_EM_RESTART': '1', 07:20:27 INFO - 'PAGER': '/bin/cat', 07:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:27 INFO - 'PWD': '/builds/slave/test', 07:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:27 INFO - 'SHELL': '/bin/bash', 07:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:27 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:27 INFO - 'USER': 'cltbld', 07:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:27 INFO - 'XPC_FLAGS': '0x0', 07:20:27 INFO - 'XPC_SERVICE_NAME': '0', 07:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:27 INFO - Downloading/unpacking psutil>=3.1.1 07:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:20:31 INFO - Installing collected packages: psutil 07:20:31 INFO - Running setup.py install for psutil 07:20:31 INFO - building 'psutil._psutil_osx' extension 07:20:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:20:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:20:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:20:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:20:32 INFO - building 'psutil._psutil_posix' extension 07:20:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:20:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:20:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:20:32 INFO - ^ 07:20:32 INFO - 1 warning generated. 07:20:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:20:32 INFO - Successfully installed psutil 07:20:32 INFO - Cleaning up... 07:20:32 INFO - Return code: 0 07:20:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:32 INFO - 'HOME': '/Users/cltbld', 07:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:32 INFO - 'LOGNAME': 'cltbld', 07:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:32 INFO - 'MOZ_NO_REMOTE': '1', 07:20:32 INFO - 'NO_EM_RESTART': '1', 07:20:32 INFO - 'PAGER': '/bin/cat', 07:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:32 INFO - 'PWD': '/builds/slave/test', 07:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:32 INFO - 'SHELL': '/bin/bash', 07:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:32 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:32 INFO - 'USER': 'cltbld', 07:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:32 INFO - 'XPC_FLAGS': '0x0', 07:20:32 INFO - 'XPC_SERVICE_NAME': '0', 07:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:20:35 INFO - Installing collected packages: mozsystemmonitor 07:20:35 INFO - Running setup.py install for mozsystemmonitor 07:20:35 INFO - Successfully installed mozsystemmonitor 07:20:35 INFO - Cleaning up... 07:20:35 INFO - Return code: 0 07:20:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07: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')]} 07:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07: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')]} 07:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:35 INFO - 'HOME': '/Users/cltbld', 07:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:35 INFO - 'LOGNAME': 'cltbld', 07:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:35 INFO - 'MOZ_NO_REMOTE': '1', 07:20:35 INFO - 'NO_EM_RESTART': '1', 07:20:35 INFO - 'PAGER': '/bin/cat', 07:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:35 INFO - 'PWD': '/builds/slave/test', 07:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:35 INFO - 'SHELL': '/bin/bash', 07:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:35 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:35 INFO - 'USER': 'cltbld', 07:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:35 INFO - 'XPC_FLAGS': '0x0', 07:20:35 INFO - 'XPC_SERVICE_NAME': '0', 07:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:20:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:38 INFO - Downloading functools32-3.2.3-2.tar.gz 07:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:20:38 INFO - warning: no files found matching '*.txt' 07:20:38 INFO - no previously-included directories found matching 'build' 07:20:38 INFO - no previously-included directories found matching 'dist' 07:20:38 INFO - no previously-included directories found matching '.git*' 07:20:38 INFO - Installing collected packages: jsonschema, functools32 07:20:38 INFO - Running setup.py install for functools32 07:20:39 INFO - warning: no files found matching '*.txt' 07:20:39 INFO - no previously-included directories found matching 'build' 07:20:39 INFO - no previously-included directories found matching 'dist' 07:20:39 INFO - no previously-included directories found matching '.git*' 07:20:39 INFO - Successfully installed jsonschema functools32 07:20:39 INFO - Cleaning up... 07:20:39 INFO - Return code: 0 07:20:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:39 INFO - 'HOME': '/Users/cltbld', 07:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:39 INFO - 'LOGNAME': 'cltbld', 07:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:39 INFO - 'MOZ_NO_REMOTE': '1', 07:20:39 INFO - 'NO_EM_RESTART': '1', 07:20:39 INFO - 'PAGER': '/bin/cat', 07:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:39 INFO - 'PWD': '/builds/slave/test', 07:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:39 INFO - 'SHELL': '/bin/bash', 07:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:39 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:39 INFO - 'USER': 'cltbld', 07:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:39 INFO - 'XPC_FLAGS': '0x0', 07:20:39 INFO - 'XPC_SERVICE_NAME': '0', 07:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:20:39 INFO - Cleaning up... 07:20:39 INFO - Return code: 0 07:20:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:39 INFO - 'HOME': '/Users/cltbld', 07:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:39 INFO - 'LOGNAME': 'cltbld', 07:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:39 INFO - 'MOZ_NO_REMOTE': '1', 07:20:39 INFO - 'NO_EM_RESTART': '1', 07:20:39 INFO - 'PAGER': '/bin/cat', 07:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:39 INFO - 'PWD': '/builds/slave/test', 07:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:39 INFO - 'SHELL': '/bin/bash', 07:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:39 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:39 INFO - 'USER': 'cltbld', 07:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:39 INFO - 'XPC_FLAGS': '0x0', 07:20:39 INFO - 'XPC_SERVICE_NAME': '0', 07:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:39 INFO - Downloading/unpacking blobuploader==1.2.4 07:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:42 INFO - Downloading blobuploader-1.2.4.tar.gz 07:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:20:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:20:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:43 INFO - Downloading docopt-0.6.1.tar.gz 07:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:20:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:20:43 INFO - Running setup.py install for blobuploader 07:20:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:20:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:20:43 INFO - Running setup.py install for requests 07:20:44 INFO - Running setup.py install for docopt 07:20:44 INFO - Successfully installed blobuploader requests docopt 07:20:44 INFO - Cleaning up... 07:20:44 INFO - Return code: 0 07:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:44 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 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:44 INFO - 'HOME': '/Users/cltbld', 07:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:44 INFO - 'LOGNAME': 'cltbld', 07:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:44 INFO - 'MOZ_NO_REMOTE': '1', 07:20:44 INFO - 'NO_EM_RESTART': '1', 07:20:44 INFO - 'PAGER': '/bin/cat', 07:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:44 INFO - 'PWD': '/builds/slave/test', 07:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:44 INFO - 'SHELL': '/bin/bash', 07:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:44 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:44 INFO - 'USER': 'cltbld', 07:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:44 INFO - 'XPC_FLAGS': '0x0', 07:20:44 INFO - 'XPC_SERVICE_NAME': '0', 07:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:44 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-YgOu4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:44 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-lNtu6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:44 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-umuYew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:44 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-E_J4zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jORZ5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-kdu3dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mo7Djd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mii9Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7GaUKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-pdfQRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-KtSuv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bx67zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-otLbaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:45 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-GZAHLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:46 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-NkBDps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:46 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Sw_n07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:46 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-HuYIJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:20:46 INFO - Running setup.py install for manifestparser 07:20:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:20:46 INFO - Running setup.py install for mozcrash 07:20:46 INFO - Running setup.py install for mozdebug 07:20:46 INFO - Running setup.py install for mozdevice 07:20:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:20:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:20:46 INFO - Running setup.py install for mozfile 07:20:47 INFO - Running setup.py install for mozhttpd 07:20:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Running setup.py install for mozinfo 07:20:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Running setup.py install for mozInstall 07:20:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Running setup.py install for mozleak 07:20:47 INFO - Running setup.py install for mozlog 07:20:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Running setup.py install for moznetwork 07:20:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:20:47 INFO - Running setup.py install for mozprocess 07:20:48 INFO - Running setup.py install for mozprofile 07:20:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:20:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:20:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:20:48 INFO - Running setup.py install for mozrunner 07:20:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:20:48 INFO - Running setup.py install for mozscreenshot 07:20:48 INFO - Running setup.py install for moztest 07:20:48 INFO - Running setup.py install for mozversion 07:20:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:20:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:20:48 INFO - Cleaning up... 07:20:48 INFO - Return code: 0 07:20:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:48 INFO - 'HOME': '/Users/cltbld', 07:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:48 INFO - 'LOGNAME': 'cltbld', 07:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:48 INFO - 'MOZ_NO_REMOTE': '1', 07:20:48 INFO - 'NO_EM_RESTART': '1', 07:20:48 INFO - 'PAGER': '/bin/cat', 07:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:48 INFO - 'PWD': '/builds/slave/test', 07:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:48 INFO - 'SHELL': '/bin/bash', 07:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:48 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:48 INFO - 'USER': 'cltbld', 07:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:48 INFO - 'XPC_FLAGS': '0x0', 07:20:48 INFO - 'XPC_SERVICE_NAME': '0', 07:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-b8xekr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jlYiXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7TOjBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-QlQIar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-CHn7CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-vwAT6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-N35Uzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-AH887y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-j60xNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mI6U0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-KlhXme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-qwwl1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sto6XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-qN60ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-5ywGS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Q4tD8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cg1hon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:20:51 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)) 07:20:51 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)) 07:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:53 INFO - Downloading blessings-1.6.tar.gz 07:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:20:53 INFO - Installing collected packages: blessings 07:20:53 INFO - Running setup.py install for blessings 07:20:53 INFO - Successfully installed blessings 07:20:53 INFO - Cleaning up... 07:20:53 INFO - Return code: 0 07:20:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:20:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:54 INFO - Reading from file tmpfile_stdout 07:20:54 INFO - Current package versions: 07:20:54 INFO - blessings == 1.6 07:20:54 INFO - blobuploader == 1.2.4 07:20:54 INFO - docopt == 0.6.1 07:20:54 INFO - functools32 == 3.2.3-2 07:20:54 INFO - jsonschema == 2.5.1 07:20:54 INFO - manifestparser == 1.1 07:20:54 INFO - mozInstall == 1.12 07:20:54 INFO - mozcrash == 0.17 07:20:54 INFO - mozdebug == 0.1 07:20:54 INFO - mozdevice == 0.48 07:20:54 INFO - mozfile == 1.2 07:20:54 INFO - mozhttpd == 0.7 07:20:54 INFO - mozinfo == 0.9 07:20:54 INFO - mozleak == 0.1 07:20:54 INFO - mozlog == 3.4 07:20:54 INFO - moznetwork == 0.27 07:20:54 INFO - mozprocess == 0.23 07:20:54 INFO - mozprofile == 0.28 07:20:54 INFO - mozrunner == 6.12 07:20:54 INFO - mozscreenshot == 0.1 07:20:54 INFO - mozsystemmonitor == 0.3 07:20:54 INFO - moztest == 0.7 07:20:54 INFO - mozversion == 1.4 07:20:54 INFO - psutil == 3.1.1 07:20:54 INFO - requests == 1.2.3 07:20:54 INFO - wsgiref == 0.1.2 07:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:54 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 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:54 INFO - 'HOME': '/Users/cltbld', 07:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:54 INFO - 'LOGNAME': 'cltbld', 07:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:54 INFO - 'MOZ_NO_REMOTE': '1', 07:20:54 INFO - 'NO_EM_RESTART': '1', 07:20:54 INFO - 'PAGER': '/bin/cat', 07:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:54 INFO - 'PWD': '/builds/slave/test', 07:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:54 INFO - 'SHELL': '/bin/bash', 07:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:54 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:54 INFO - 'USER': 'cltbld', 07:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:54 INFO - 'XPC_FLAGS': '0x0', 07:20:54 INFO - 'XPC_SERVICE_NAME': '0', 07:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:20:54 INFO - Cleaning up... 07:20:54 INFO - Return code: 0 07:20:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec27490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88684884b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, '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.gdgmovStoX/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.TL62PJgcUp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:54 INFO - 'HOME': '/Users/cltbld', 07:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:54 INFO - 'LOGNAME': 'cltbld', 07:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:54 INFO - 'MOZ_NO_REMOTE': '1', 07:20:54 INFO - 'NO_EM_RESTART': '1', 07:20:54 INFO - 'PAGER': '/bin/cat', 07:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:54 INFO - 'PWD': '/builds/slave/test', 07:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:54 INFO - 'SHELL': '/bin/bash', 07:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:20:54 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:54 INFO - 'USER': 'cltbld', 07:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:54 INFO - 'XPC_FLAGS': '0x0', 07:20:54 INFO - 'XPC_SERVICE_NAME': '0', 07:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:20:54 INFO - Cleaning up... 07:20:54 INFO - Return code: 0 07:20:54 INFO - Running post-action listener: _resource_record_post_action 07:20:54 INFO - Running post-action listener: _start_resource_monitoring 07:20:54 INFO - Starting resource monitoring. 07:20:54 INFO - [mozharness: 2016-10-16 14:20:54.791289Z] Finished create-virtualenv step (success) 07:20:54 INFO - [mozharness: 2016-10-16 14:20:54.792291Z] Running install step. 07:20:54 INFO - Running pre-action listener: _resource_record_pre_action 07:20:54 INFO - Running main action method: install 07:20:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:55 INFO - Reading from file tmpfile_stdout 07:20:55 INFO - Detecting whether we're running mozinstall >=1.0... 07:20:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:20:55 INFO - Reading from file tmpfile_stdout 07:20:55 INFO - Output received: 07:20:55 INFO - Usage: mozinstall [options] installer 07:20:55 INFO - Options: 07:20:55 INFO - -h, --help show this help message and exit 07:20:55 INFO - -d DEST, --destination=DEST 07:20:55 INFO - Directory to install application into. [default: 07:20:55 INFO - "/builds/slave/test"] 07:20:55 INFO - --app=APP Application being installed. [default: firefox] 07:20:55 INFO - mkdir: /builds/slave/test/build/application 07:20:55 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'] 07:20:55 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 07:21:29 INFO - Reading from file tmpfile_stdout 07:21:29 INFO - Output received: 07:21:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:21:29 INFO - Running post-action listener: _resource_record_post_action 07:21:29 INFO - [mozharness: 2016-10-16 14:21:29.526829Z] Finished install step (success) 07:21:29 INFO - [mozharness: 2016-10-16 14:21:29.526968Z] Running run-tests step. 07:21:29 INFO - Running pre-action listener: _resource_record_pre_action 07:21:29 INFO - Running main action method: run_tests 07:21:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:21:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:21:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:21:29 INFO - 2016-10-16 07:21:29.541 screenresolution[1654:8840] starting screenresolution argv=screenresolution get 07:21:29 INFO - 2016-10-16 07:21:29.544 screenresolution[1654:8840] Display 0: 1600x1200x32@60 07:21:29 INFO - 2016-10-16 07:21:29.549 screenresolution[1655:8842] starting screenresolution argv=screenresolution list 07:21:29 INFO - Available Modes on Display 0 07:21:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:21:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:21:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:21:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:21:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:21:29 INFO - Intel Iris: 07:21:29 INFO - Chipset Model: Intel Iris 07:21:29 INFO - Type: GPU 07:21:29 INFO - Bus: Built-In 07:21:29 INFO - VRAM (Dynamic, Max): 1536 MB 07:21:29 INFO - Vendor: Intel (0x8086) 07:21:29 INFO - Device ID: 0x0a2e 07:21:29 INFO - Revision ID: 0x0009 07:21:29 INFO - Displays: 07:21:29 INFO - Display: 07:21:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:21:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:21:29 INFO - Display Serial Number: 1600x1200 60 07:21:29 INFO - Main Display: Yes 07:21:29 INFO - Mirror: Off 07:21:29 INFO - Online: Yes 07:21:29 INFO - Rotation: Supported 07:21:29 INFO - Return code: 0 07:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:21:29 INFO - Python 2.7.3 07:21:29 INFO - Return code: 0 07:21:29 INFO - grabbing minidump binary from tooltool 07:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3bd30>, '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 0x10e861db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea33b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:21:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:21:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:21:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:21:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:21:29 INFO - Return code: 0 07:21:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:21:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:21:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:21:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:21:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:21:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdgmovStoX/Render', 07:21:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:29 INFO - 'HOME': '/Users/cltbld', 07:21:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:29 INFO - 'LOGNAME': 'cltbld', 07:21:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:21:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:29 INFO - 'MOZ_NO_REMOTE': '1', 07:21:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:21:29 INFO - 'NO_EM_RESTART': '1', 07:21:29 INFO - 'PAGER': '/bin/cat', 07:21:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:29 INFO - 'PWD': '/builds/slave/test', 07:21:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:21:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:29 INFO - 'SHELL': '/bin/bash', 07:21:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TL62PJgcUp/Listeners', 07:21:29 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 07:21:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:29 INFO - 'USER': 'cltbld', 07:21:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:29 INFO - 'XPC_FLAGS': '0x0', 07:21:29 INFO - 'XPC_SERVICE_NAME': '0', 07:21:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:21:30 INFO - mozversion application_buildid: 20161016062235 07:21:30 INFO - mozversion application_changeset: 9079d167112122805f99f57bb8856e1b1675af0f 07:21:30 INFO - mozversion application_display_name: Nightly 07:21:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:21:30 INFO - mozversion application_name: Firefox 07:21:30 INFO - mozversion application_remotingname: firefox 07:21:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:21:30 INFO - mozversion application_vendor: Mozilla 07:21:30 INFO - mozversion application_version: 52.0a1 07:21:30 INFO - mozversion platform_buildid: 20161016062235 07:21:30 INFO - mozversion platform_changeset: 9079d167112122805f99f57bb8856e1b1675af0f 07:21:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:21:30 INFO - mozversion platform_version: 52.0a1 07:21:30 INFO - using testdate: 1476627690 07:21:30 INFO - actual date: 1476627690 07:21:30 INFO - starting webserver on 'localhost:49276' 07:21:30 INFO - SUITE-START | Running 2 tests 07:21:30 INFO - TEST-START | dromaeo_css 07:21:30 INFO - operating with platform_type : mac_ 07:21:30 INFO - Initialising browser for dromaeo_css test... 07:21:30 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpJmgGuE/profile http://localhost:49276/getInfo.html) 07:21:32 INFO - PROCESS | 1665 | 2016-10-16 07:21:32.959 plugin-container[1668:9014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 07:21:32 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:32 INFO - PROCESS | 1665 | 2016-10-16 07:21:32.962 plugin-container[1668:9014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x901b, name = 'com.apple.CFPasteboardClient' 07:21:32 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:32 INFO - PROCESS | 1665 | 2016-10-16 07:21:32.962 plugin-container[1668:9014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:21:33 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 07:21:33 INFO - PROCESS | 1665 | colorDepth:24 07:21:33 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 07:21:33 INFO - PROCESS | 1665 | __metrics 07:21:33 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:21:33 INFO - TEST-INFO | 1665: exit 0 07:21:33 INFO - Browser initialized. 07:21:33 INFO - Running cycle 1/1 for dromaeo_css test... 07:21:33 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpJmgGuE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:21:36 INFO - PROCESS | 1669 | 2016-10-16 07:21:36.380 plugin-container[1670:9134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 07:21:36 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:36 INFO - PROCESS | 1669 | 2016-10-16 07:21:36.380 plugin-container[1670:9134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:21:36 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:36 INFO - PROCESS | 1669 | 2016-10-16 07:21:36.380 plugin-container[1670:9134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:21:46 INFO - PROCESS | 1669 | RSS: Main: 237539328 07:21:46 INFO - PROCESS | 1669 | 07:22:35 INFO - PROCESS | 1669 | 1476627755736 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:24:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 07:24:51 INFO - PROCESS | 1669 | RSS: Main: 250146816 07:24:51 INFO - PROCESS | 1669 | 07:27:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 07:27:54 INFO - PROCESS | 1669 | RSS: Main: 262512640 07:27:54 INFO - PROCESS | 1669 | 07:30:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 07:30:57 INFO - PROCESS | 1669 | RSS: Main: 267288576 07:30:57 INFO - PROCESS | 1669 | 07:33:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 07:33:59 INFO - PROCESS | 1669 | RSS: Main: 268521472 07:33:59 INFO - PROCESS | 1669 | 07:37:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 07:37:03 INFO - PROCESS | 1669 | RSS: Main: 273903616 07:37:03 INFO - PROCESS | 1669 | 07:40:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 07:40:06 INFO - PROCESS | 1669 | RSS: Main: 276697088 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | __start_tp_report 07:40:06 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 07:40:06 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 07:40:06 INFO - PROCESS | 1669 | |i|pagename|runs| 07:40:06 INFO - PROCESS | 1669 | |0;dojo.html;3339.1318257253124;3379.4254976653965;3405.5402520659704;3415.265717870659;3417.1798768295616;5600.52395546378;5605.943940560594;5652.576056795741;5664.518515926151;5746.48281654651;5630.521405680512;5697.77208911643;5703.914441283374;5749.511291540213;5783.942160578393;5179.585633149358;5188.4811518848;5256.3955145158225;5303.814366497173;5315.7873685052555;5207.583393328544;5325.440828712987;5342.759575819097;5366.651167674088;5408.783648654049;5105.438401775802;5111.080005599028;5187.507186817246;5220.3996540397775;5275.472452754732;5168.664036837594;5232.215167724872;5251.501107394792;5259.000789849916;5295.338082739658;5177.45636708144;5177.870553236162;5185.040767457995;5186.611004174702;5223.921641175385;4050.4936882889638;5254.607529004817;5553.583481238883;5738.282714660668;5740.6268592541355;5183.403908550527;5589;5624.67820062285;6140.508759299245;6224.09750586167;6254.030625253093;6261.499080073583;6266.279377871558;6269.310375858652;6292;5458.972705136449;5757.222774925415;5770.278715160605;5793.008814799759;5847.327557330961;5550.639208451437;6119.502553941423;6172.259328880563;6191.4118158774845;6212.596181248295;6117.694115294217;6157.138000679908;6165.815025549241;6165.938340616626;6172.166757487771;5625.296837895263;6098.522998457079;6215.844603884939;6240.095186198067;6300;5109.770060347293;6115.605440958429;6128.19758601264;6200.658963757037;6276.403741644628;6213.2233470816145;6269.749210031548;6281.654509001957;6284.402981716729;6306.873862522724;4952.356193694796;5017.347744793226;5018.774155163026;5022.89954200914;5053.9241911371355;4364.345348197732;4376.934345984812;4411.228035093845;4465.084657645175;4523.705959112647;5902.704864756745;5910.852228694317;5956;5961.940380596138;5973.671448070311;5277.313949186581;6250.6874656267;6268.529860260499;6271.435570798559;6273.12176295319;5717.42825717425;5789.884202316014;5792.623479473821;5802.361740208574;5938.168656388109;5395.568354531707;5427.402985671573;5434.619576629677;5449.264349312872;5482.588805839578;6323.873522529616;6333.778317758856;6337.302896681362;6357.745690172342;6373.8725225496155;6133.509319254538;6151.323354431099;6182.69086545662;6206.348333425106;6231.470325022424;6288.056791481315;6364.77723279683;6420.582662126947;6426.228852537725;6437.905556055575;6174.320824709189;6239;6255.812325630239;6270.435660790551;6272.937270627235;6124.846878828065;6206.162168107247;6294.1188233648245;6304.684765761602;6307.842303942438;5919.348871624204;6060.2424696912885;6100.944524685144;6181.907271391023;6184.257889053342;6092.939070609237;6141.6007959482495;6146.631202127886;6150.876982460415;6181.96909015452;4995.300657907968;5824.15549745293;5847.181394604674;5883.35283118848;6057.242844644419;6097.887666312708;6110.236220472441;6119.051547010221;6137.048757442603;6139.754409823557;5949.077892926603;5971.193888824953;5982.4316689914385;5984.251968503937;5987.850303742442 07:40:06 INFO - PROCESS | 1669 | |1;ext.html;456.81498992907876;531.500389633744;533.1176902226815;539.4093467652921;542.8561431220725;39149.412758808605;40295.597044029586;40589.59410405899;40670.79664601673;40685.98285042875;36972.81513592435;42606.147877042444;42759.3586096208;42930.356044659275;42932;9491.572879220452;9541.618335266616;9668.613255469772;9673.322867399285;9767.462789546573;9443.763905902373;9486.098820612031;9525.380850244746;9527.475988820612;9537.618495260216;9595.328327017112;9602.079584083176;9605.711828645151;9615.95192024039;9689.903100969006;9547.283953703465;9562.904370956307;9567.665131720427;9569.569369378394;9607.231421486264;8277.130901255341;9380;9460.195883349923;9563.043695630451;9587;32693.18267043319;39947.403418777976;40019.99950001244;40355.59644403577;40602.79698601518;3503.474478828196;3543.911402214965;3570.2145527983803;3897.4933258676474;4033.6369726724733;30362.93729719449;30695.69304306973;31189.37621247585;31450.37099258025;31989.880354187724;3161.1148878314107;3186.5857438533076;3201;3211.454052811028;3263.820489873056;494135;495957;501616;503112;503427.99999999633;58129;58299.70850145722;58302.70848645814;58363;58518.707406462694;59991.70004149951;60727;61150.69424652848;61207.387926120166;61286.080708789406;16465.67068658644;16687.165641717864;16765.58086047834;16786.160691965353;16786.244618992176;13014.739705206031;13020.934895325652;13127.934360328138;13134.211947283193;13154.947604191642;15211.08733475995;15515.836312276624;15557.14435706047;15603.921980390025;15639.530814075595;4767.475577686451;4804.615630749532;5249.42256351801;5335.519803217729;6064.605800623033;723.3074605423335;906.7914379692708;933.2580598424269;1122.3939072900705;1177.270092542629;27160;28646.427071458453;28727.856360718062;30613.540796888065;30617.16297022186;991.4943378876825;1070.4861666400016;1073.5759375046812;1075.671920064388;1110.1119104716356;15572.159256893554;15629.76555351648;15661.765073523906;15816.446424375092;15963.36146554148;546508;625101;633378;633762;634093;58453.41546584565;58475.83048339009;58476.83046339134;58550.7072464635;58658.12012819811;1348.8044233586272;1392.1368751374214;1414.8514405987428;1415.0094933546352;1415.405529677538;23755.179980904788;30668.693313066582;31367.90212342557;32077.198070048435;32637.836810815796;48379.03241935071;56773;57200.71399642975;57322;57403;570935;572527;578071;578877;580017;25631.23106306814;25740;25842.483150336513;25858.094966676075;25877.741222587534;47158.82102947454;47187.82030449266;48143.27785083157;48655;49214.753926230136;39655.60344396519;39764.602353976086;39887.800560997006;39912.201755965296;42318.57681423146;39230;39586.604133959445;40095;40538.1892362157;40795.38806917958;32801.34397312088;33336.666633333356;39025.414618780174;39201.019974500865;39278.01804954899;29674.25814354572;32696.836515817267;33599.65601375917;34981.47527787034;35808.46287305641;25435.74564254408;25521.61717574275;25764.742352576235;25879.22362329133;26413.60379594346 07:40:06 INFO - PROCESS | 1669 | |2;jquery.html;322.9773915825892;340.50796598914565;376.16116061183567;377.8639689711705;402.376316709101;19634;20978.89510552447;21222.57554848902;21264.89367553164;21479.463013424673;20265.189392424287;20717;20801.27195548156;21021.68467472985;21278;5016.673916195454;5373.650712703681;5463.972680136594;5539.169124631298;5731.971340143315;5651.2653355063785;5711.314642242936;5751.367349591542;5755.963926493229;5771.22088518051;4696.953030469703;5544.972275138619;5593.161025846115;5594.9720251398885;5716.22830917827;5644.238027866227;5649.0114230009785;5656.886862262772;5690.317161940573;5693.3452652945025;4770.642201834877;4936.48166942473;4945.332380128709;5004.074246264453;5013.699178049329;24912.377190570198;25299.114530991326;25366.492670146494;25475.980960761546;25676.48647027049;2206.2829580386438;2281.0533628544135;2407.4222186675247;2411.3127758588894;2697.5414179589516;15331.923340383339;15434;15566.455174068853;15960.920195398949;15969.281382337707;1780.2700892634086;1801.2524802207067;1830.7436958825774;1853.2309091726918;1853.2309091727052;102440.97559024389;102709.48645256876;103752;104025.4798726009;104363.47818260935;9318;9577.425354478753;9722.451305273524;9878.654247101316;10913.672589822318;11180.944095279472;12044.939775301244;12460;13035;13765.793513097311;8586.828263434696;8618.095100014534;8690.348223883235;8712.869306960401;8905.688300909436;7181.245969173162;8731.869021964676;8739.082396348418;8795.164459376349;8868;6519.478441724651;6630.403263706289;6659.733610655519;6672.2994085621285;6672.966635166793;1606.967860642804;1853.684873571469;1874.6344462829618;1885.4249453916414;1939.4375631067114;696.905917701114;780.9574218418361;901.1844280925773;917.5870858113875;1057.1754031855164;19390.4182874514;20575.897120514303;22110.77889221087;22313.107475700792;22357.329280121623;410.5914614958136;413.4893406642794;426.11368353823974;430.7014351729538;431.37450696490214;10766.354018758899;11554.191206615456;11618.76762464746;12106.010787829393;12128.090393220544;582824;595224;598411;601407;609657;121526;133074;137596;140165.2991735055;150978;421.5131523090774;440.79943625650253;445.09423323536447;445.730333148447;447.5792754810533;9724.659636912957;10707.892921071001;11029.338239705643;11316.038136758467;11535.480903359201;53696;55055;56254.71872640611;56826.14760778509;57733.42266577281;74042;75745;80732.78900816375;84816;84852.57573712092;7560.319571238615;7575.583342916192;7932.524048557105;8069.103760616368;8198.303144232807;10676.73308167271;11850.288982661068;12153;12804.551840685532;13183.934080329536;10474.423906685206;11025.724356891142;11184.21710480291;11551.075913927034;11667.416629168338;11115.944420277847;11156.609518666808;11196.216264861707;11467.598634048101;11488.712782180512;11326.433678315887;11458.083353331547;11856.22934509254;11939.582114625946;12004.339761313211;6725.529212955046;6944.375006249462;8150.551719655238;8236.299914507093;8391.412601117863;10729.731756706145;10925.726856828644;11027.83458248111;11335.546578136782;11393.829092563452 07:40:06 INFO - PROCESS | 1669 | |3;mootools.html;1403.417581703593;1479.8372179060304;1526.2979029646363;1785.2055835153353;1937.9321723739672;7862.488938219026;8209;8528.317734581235;9065.093490650932;9170.220531254843;8545.829083418328;8843.646254149826;8876.95561522193;9006.729798106051;9012;2366.065404165358;2367.775061369176;2368.692070030894;2376.1327115602853;2381.5475059738596;2352.2590384029063;2364.8935797889135;2399.436132508859;2401.627747699101;2416.8429052111555;2373.1456675596773;2380.119355838342;2392.162743039931;2401.327628264089;2405.891734871926;1839.3010655950786;2049.405672355015;2330.4057465346314;2347.6713260143592;2386.2721869829693;1980.5345743750206;2351.341624345169;2352.5765362234793;2353.341064501942;2359.197872723282;8925.955370223108;9127.40671856334;9134.31492638048;9135.177833994972;9162.31282653797;5286.5770738341225;5369.301990741179;5468.0977638689565;5469.535089517396;5480.095784195603;3476.148343655795;3545.8936231913085;3665.963340366589;3667.908302292464;3677.797721125337;1012.6202673997251;1050.5220124843179;1051.6529545250125;1058.8358804385332;1079.6545105566222;10540.947295263475;10837.891621083845;11799.705007374798;11866.228695134789;11903.226290291102;4536.844998625626;4648.418947631546;4783.808647654125;4854.2233242681705;4877.463479017305;8711.651533938571;10148.98510148994;10180.032896874785;10194.235432342455;10195.949020254851;5330.440303768049;5382.300300960928;5390.164524498709;5402.756875940592;5405.135178371442;4437.134758722018;4605.746683932349;4741.38362012943;4875.926861097087;4915.557599815962;3913.2173565287057;3951.565327813938;4025.3156963316305;4048.7975601219823;4087.3664581989838;1287.6909541709922;1302.6222395505388;1304.9869501305054;1307.1307580458888;1311.8556958734532;930.5858892792643;933.1554942776797;934.6914319952122;952.1716106986966;986.7237173591406;5953.434423729739;6002.4897883679505;6058.454739073504;6059.27288725347;6083.908741368883;415.93137132373425;421.084141991168;431.91577642359437;442.9446319210099;450.9492881585897;4003.259397011562;4011.6991225658194;4012.8194231259663;4145.253854306254;4147.2534943710425;10484.842727359095;10720.946395268129;10830.945845270724;10850.023497885228;10869.782604348027;10782.867798881005;10790.406527641055;10800.891991079989;10808.97314755097;10830.945845270724;8256.711015114442;8331.875021874677;8343.95828020868;8362.038365588036;8363.874541881876;5209.921851172311;5655.632383895034;5705.800296989585;5750.7987220447085;6017.699115044143;9779.364341317792;9972.00279972026;10022.949885250528;10033.949830250802;10045.899541004497;9617.855732164164;9949;10123.392596444237;10148.695539133838;10193.694189174337;6942.861142777015;7163.606001669749;7464.776056718307;7508.849823003618;7747.418943579141;8388.454750441202;8607.268382187583;8616.78458038554;8617.439866408664;8619.569021549023;7500.887486687595;7653.3494652952895;7683.884741728991;7745.341645960157;7801;7353.448491363062;7508.699652013858;7526.473146879666;7547.698092076255;7691.4615976883315;6993.580385176906;7147.499675022698;7156.033935418652;7176.067111275501;7178.497505174588;4973.104841128632;5008.799648014185;5117.5394214520875;5275.709835959059;5363.088274993339;4998.275250088789;5108.974455127701;5189;5256.816011439581;5297.655652382583 07:40:06 INFO - PROCESS | 1669 | |4;prototype.html;229.87816457277637;289.98840046398146;300.22242392204214;313.58606639236206;314.58631899052756;5245.659032162916;5412.161115027167;5412.702301373423;5480.246321177225;5541.556675465963;4955.578775804061;5101.7194054327;5206.7917283308625;5347.465253474651;5420.566354691625;3714.9071273218115;4173.5409104998425;4183.686223533247;4186.79066046698;4187.204431158086;3757.8872633820893;4098;4169.916601667965;4175.436316097318;4214.726042807208;3613.385724426854;4054.128362402083;4054.3918412238254;4059.939100913489;4060.4924384451942;4068.7965601719943;4081.265372232997;4082.6937979651643;4092.1815636872743;4116.670666346685;3133.764967627414;3311.5760223104057;3337.2658015236852;3511.8059859647587;3522.5068490411363;5269.420363759983;5311.62818602698;5325.520703136736;5342.332208473941;5343.412224655285;3938.448617193595;4687.20317546018;4691.319758634979;4715.127701375222;4727.5272472752795;5163.657449063233;5288.709120998344;5384.6499977501335;5642.6614403135945;5745.48290653839;3864.942025869614;3868.458415821787;3878.0692633767976;3890.074413165473;3902.863399780994;4893.60851131905;5278.287431196778;6045.153678485016;6088.4215999479975;6153.415425534612;3642.6357364263363;3885.6891448684037;3908.335582950848;3938.542739186519;3944;3962.841486340514;3966.484357033567;4008.7594744315434;4013.979930100331;4023.8994025149605;4439.378487011821;4622.537746225351;4677.836275730333;4700.37836946253;4711.764411779397;5433.483819037185;5670.291213598301;5833.445822646841;5836.824895253149;5851.824445266649;1252.9624111276676;1273.605182393461;1309.6594885329691;1343.932803359828;1349.406261245049;762.4777027736019;814.1776805426562;861.6208868098017;899.6356475627381;918.6555041859302;4976.776045077981;5361.597880159004;5394.78420863161;5421.939662928062;5423.694929654881;5504.256925315111;5846.766129354778;5871.178035075093;5883.499902508249;5921.644701317935;4582.450105987303;4851.792434442391;5072.731817045738;5378.772736358232;5398.544931243929;2780.430011847566;2844.4737723520802;3107.9689203107678;3112.937741245162;3133.6082989626298;5812.854678632983;5855.950417353892;6150.7847225346895;6151.200343955346;6170.598911070766;4717.90053426222;5974.492168165668;6005.729742161613;6084.665343406066;6098.69506524672;6186.659733714599;6194.163787888578;6220.844478887973;6240.4071613196675;6253.906191407224;5216.269722238813;5266.78932842682;5775.797847075332;5839.2190381933915;5911.645301281937;4738.928916066331;5193.532582067632;5825.330087039875;5850.619709718854;5883.852903677443;5183.403908550414;6124.284301088226;6143.170671959407;6169.334279744534;6214.161088253028;2976.2410585301477;3129.123845323313;3617.4987380236907;3859.150986782903;3883.776610367725;4936.950630493793;5688.857778555569;5827.388124246893;5858.47273745365;5889.234399528034;4840.6127509799835;5508.9724551376985;5726.770929162787;5729.885402291848;5760.740766665426;5010.398752149766;5763.279590051244;5781.566382521243;5795.217645617788;5795.8551036224435;5326.866828329323;5620.831375058755;5649.519790817826;5682.943170568242;5700.144978253129;5337.172738225581;5477.72611369422;5547.805826796042;5862.355140934408;5893.764249429974;4751.477337492804;4992.400911890451;5039.798408063783;5458.935507575985;5632.49307562305 07:40:06 INFO - PROCESS | 1669 | |5;yui.html;700.6426722371591;700.6496751624192;744.7802898145046;778.3889646627399;832.9042160151582;12778.361081945912;15157.924210378946;15529.611759706013;16030.75953860693;16031.957922735013;14613.342399592017;14633.341499632515;14715.26423678817;14776.743976761989;14780.556583302492;715.3383120613412;888.5868071346839;898.6944438890775;899.491787140267;900.4642237868458;705.8023753349071;729.7555318968125;897.1880448194395;906.2387594420685;912.4434095201922;944.0465130218495;964.4406244378243;967.9806403871918;969.8787651543557;972.1687956796943;961.9134277914986;962.8026254617787;962.9229661627053;963.648268382039;963.6627180486844;684.5721424109931;685.1640997982454;702.8137543550963;715.1275443958363;716.0297796485739;15100.697986040217;15208.16355100469;15229.923850380788;15243.542693719206;15255.923720381326;13598.184108953395;14604.269786510631;14668.413263469449;14965;15139.621509462351;11881.94059029708;11947;12001.819972700416;12056.879431205663;12063.095267854858;3751.924961500782;3772.264408440355;3808.371618682914;3832.5784163741964;3922.2743792398496;109597;110130;112088.43955780168;113936;115942.42028789801;9360.672376466791;11332.036776874058;11408.315501069963;11412.775985244369;11511.90636889494;19493.512662183275;23635.054597815895;24051;24103.879480602485;24290.635640465407;8007.399445041528;8030.196980301923;8065.556394398247;8122.350211983027;8208.015038195455;4420.646348292129;4493.550644935545;4519.502854685982;4524.909501809945;4546.090781843578;3180.9204769880475;3632.6730594246114;3772.0569857535615;3788.450674652215;3796.126890815128;528.5718567959965;601.0323379359228;603.4418163199024;644.3846126948753;653.8365408647838;580.9608274954938;590.1472372421895;646.6249575246346;661.4873473058323;680.2789043613785;9359.672411465566;9445.008274131254;9513.952430237805;9754.317197796086;9819.705408837746;636.3350298937529;666.277089358052;671.4998811504585;679.6431873266574;684.2815044203567;3698.815059247027;4424.668149888771;4675.883102922386;4722;4738.786754596052;129026.35486822693;154245;161077.19461402617;161233;161533;100795.49602252088;126378.10432843515;137300.31349843385;137585;141591.58408415783;87668.56165719131;94472.52763736137;117728.41135794438;119191.67679070475;119933.80066199401;9032.774180645536;9063.229625481908;9072.682456114004;9135.08453986011;9153.771155720893;61330.69334653298;63550.68224658847;63820.404489888126;67390.66304668445;67919.3208067913;82988.34023319378;87219.38341849754;96719;98036.07855685978;103025.48487257515;11902.642920712393;12305.44625491873;12437.62687119388;12504.874951250371;12846.807297890726;12865.549705760253;13117.540886068942;13354.666133346744;13482.662933426744;14138.717225655224;12040.458179381774;12112.093097656323;12122.818157727817;12127.215901492416;12602.054845886412;12378.62864114078;12489.500419983098;12500.687482813002;12507.56223532172;12583.68540786451;12109.33398663082;12152.817707734568;12156.756864862477;12188.268703877795;12234.388280585757;9834.164096051916;10376.636817711344;10558.419286938984;10566.366018038942;11122.944385278022;9987.350822196535;10106.14097801695;10130.392176469435;10170.949145254226;10201.030902064293 07:40:06 INFO - PROCESS | 1669 | __end_tp_report 07:40:06 INFO - PROCESS | 1669 | __start_cc_report 07:40:06 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,372 07:40:06 INFO - PROCESS | 1669 | __end_cc_report 07:40:06 INFO - PROCESS | 1669 | __startTimestamp1476628806152__endTimestamp 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | ------- Summary: start ------- 07:40:06 INFO - PROCESS | 1669 | Number of tests: 6 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5716.70 Median:5919.35 stddev:646.47 (10.9%) stddev-sans-first:620.97 07:40:06 INFO - PROCESS | 1669 | Values: 3339.1 3379.4 3405.5 3415.3 3417.2 5600.5 5605.9 5652.6 5664.5 5746.5 5630.5 5697.8 5703.9 5749.5 5783.9 5179.6 5188.5 5256.4 5303.8 5315.8 5207.6 5325.4 5342.8 5366.7 5408.8 5105.4 5111.1 5187.5 5220.4 5275.5 5168.7 5232.2 5251.5 5259.0 5295.3 5177.5 5177.9 5185.0 5186.6 5223.9 4050.5 5254.6 5553.6 5738.3 5740.6 5183.4 5589.0 5624.7 6140.5 6224.1 6254.0 6261.5 6266.3 6269.3 6292.0 5459.0 5757.2 5770.3 5793.0 5847.3 5550.6 6119.5 6172.3 6191.4 6212.6 6117.7 6157.1 6165.8 6165.9 6172.2 5625.3 6098.5 6215.8 6240.1 6300.0 5109.8 6115.6 6128.2 6200.7 6276.4 6213.2 6269.7 6281.7 6284.4 6306.9 4952.4 5017.3 5018.8 5022.9 5053.9 4364.3 4376.9 4411.2 4465.1 4523.7 5902.7 5910.9 5956.0 5961.9 5973.7 5277.3 6250.7 6268.5 6271.4 6273.1 5717.4 5789.9 5792.6 5802.4 5938.2 5395.6 5427.4 5434.6 5449.3 5482.6 6323.9 6333.8 6337.3 6357.7 6373.9 6133.5 6151.3 6182.7 6206.3 6231.5 6288.1 6364.8 6420.6 6426.2 6437.9 6174.3 6239.0 6255.8 6270.4 6272.9 6124.8 6206.2 6294.1 6304.7 6307.8 5919.3 6060.2 6100.9 6181.9 6184.3 6092.9 6141.6 6146.6 6150.9 6182.0 4995.3 5824.2 5847.2 5883.4 6057.2 6097.9 6110.2 6119.1 6137.0 6139.8 5949.1 5971.2 5982.4 5984.3 5987.9 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:69702.47 Median:40563.89 stddev:151116.08 (372.5%) stddev-sans-first:151450.56 07:40:06 INFO - PROCESS | 1669 | Values: 456.8 531.5 533.1 539.4 542.9 39149.4 40295.6 40589.6 40670.8 40686.0 36972.8 42606.1 42759.4 42930.4 42932.0 9491.6 9541.6 9668.6 9673.3 9767.5 9443.8 9486.1 9525.4 9527.5 9537.6 9595.3 9602.1 9605.7 9616.0 9689.9 9547.3 9562.9 9567.7 9569.6 9607.2 8277.1 9380.0 9460.2 9563.0 9587.0 32693.2 39947.4 40020.0 40355.6 40602.8 3503.5 3543.9 3570.2 3897.5 4033.6 30362.9 30695.7 31189.4 31450.4 31989.9 3161.1 3186.6 3201.0 3211.5 3263.8 494135.0 495957.0 501616.0 503112.0 503428.0 58129.0 58299.7 58302.7 58363.0 58518.7 59991.7 60727.0 61150.7 61207.4 61286.1 16465.7 16687.2 16765.6 16786.2 16786.2 13014.7 13020.9 13127.9 13134.2 13154.9 15211.1 15515.8 15557.1 15603.9 15639.5 4767.5 4804.6 5249.4 5335.5 6064.6 723.3 906.8 933.3 1122.4 1177.3 27160.0 28646.4 28727.9 30613.5 30617.2 991.5 1070.5 1073.6 1075.7 1110.1 15572.2 15629.8 15661.8 15816.4 15963.4 546508.0 625101.0 633378.0 633762.0 634093.0 58453.4 58475.8 58476.8 58550.7 58658.1 1348.8 1392.1 1414.9 1415.0 1415.4 23755.2 30668.7 31367.9 32077.2 32637.8 48379.0 56773.0 57200.7 57322.0 57403.0 570935.0 572527.0 578071.0 578877.0 580017.0 25631.2 25740.0 25842.5 25858.1 25877.7 47158.8 47187.8 48143.3 48655.0 49214.8 39655.6 39764.6 39887.8 39912.2 42318.6 39230.0 39586.6 40095.0 40538.2 40795.4 32801.3 33336.7 39025.4 39201.0 39278.0 29674.3 32696.8 33599.7 34981.5 35808.5 25435.7 25521.6 25764.7 25879.2 26413.6 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:34850.79 Median:1510.26 stddev:99755.83 (6605.2%) stddev-sans-first:100001.99 07:40:06 INFO - PROCESS | 1669 | Values: 323.0 340.5 376.2 377.9 402.4 19634.0 20978.9 21222.6 21264.9 21479.5 20265.2 20717.0 20801.3 21021.7 21278.0 5016.7 5373.7 5464.0 5539.2 5732.0 5651.3 5711.3 5751.4 5756.0 5771.2 4697.0 5545.0 5593.2 5595.0 5716.2 5644.2 5649.0 5656.9 5690.3 5693.3 4770.6 4936.5 4945.3 5004.1 5013.7 24912.4 25299.1 25366.5 25476.0 25676.5 2206.3 2281.1 2407.4 2411.3 2697.5 15331.9 15434.0 15566.5 15960.9 15969.3 1780.3 1801.3 1830.7 1853.2 1853.2 102441.0 102709.5 103752.0 104025.5 104363.5 9318.0 9577.4 9722.5 9878.7 10913.7 11180.9 12044.9 12460.0 13035.0 13765.8 8586.8 8618.1 8690.3 8712.9 8905.7 7181.2 8731.9 8739.1 8795.2 8868.0 6519.5 6630.4 6659.7 6672.3 6673.0 1607.0 1853.7 1874.6 1885.4 1939.4 696.9 781.0 901.2 917.6 1057.2 19390.4 20575.9 22110.8 22313.1 22357.3 410.6 413.5 426.1 430.7 431.4 10766.4 11554.2 11618.8 12106.0 12128.1 582824.0 595224.0 598411.0 601407.0 609657.0 121526.0 133074.0 137596.0 140165.3 150978.0 421.5 440.8 445.1 445.7 447.6 9724.7 10707.9 11029.3 11316.0 11535.5 53696.0 55055.0 56254.7 56826.1 57733.4 74042.0 75745.0 80732.8 84816.0 84852.6 7560.3 7575.6 7932.5 8069.1 8198.3 10676.7 11850.3 12153.0 12804.6 13183.9 10474.4 11025.7 11184.2 11551.1 11667.4 11115.9 11156.6 11196.2 11467.6 11488.7 11326.4 11458.1 11856.2 11939.6 12004.3 6725.5 6944.4 8150.6 8236.3 8391.4 10729.7 10925.7 11027.8 11335.5 11393.8 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5745.93 Median:4819.02 stddev:3232.81 (67.1%) stddev-sans-first:3225.40 07:40:06 INFO - PROCESS | 1669 | Values: 1403.4 1479.8 1526.3 1785.2 1937.9 7862.5 8209.0 8528.3 9065.1 9170.2 8545.8 8843.6 8877.0 9006.7 9012.0 2366.1 2367.8 2368.7 2376.1 2381.5 2352.3 2364.9 2399.4 2401.6 2416.8 2373.1 2380.1 2392.2 2401.3 2405.9 1839.3 2049.4 2330.4 2347.7 2386.3 1980.5 2351.3 2352.6 2353.3 2359.2 8926.0 9127.4 9134.3 9135.2 9162.3 5286.6 5369.3 5468.1 5469.5 5480.1 3476.1 3545.9 3666.0 3667.9 3677.8 1012.6 1050.5 1051.7 1058.8 1079.7 10540.9 10837.9 11799.7 11866.2 11903.2 4536.8 4648.4 4783.8 4854.2 4877.5 8711.7 10149.0 10180.0 10194.2 10195.9 5330.4 5382.3 5390.2 5402.8 5405.1 4437.1 4605.7 4741.4 4875.9 4915.6 3913.2 3951.6 4025.3 4048.8 4087.4 1287.7 1302.6 1305.0 1307.1 1311.9 930.6 933.2 934.7 952.2 986.7 5953.4 6002.5 6058.5 6059.3 6083.9 415.9 421.1 431.9 442.9 450.9 4003.3 4011.7 4012.8 4145.3 4147.3 10484.8 10720.9 10830.9 10850.0 10869.8 10782.9 10790.4 10800.9 10809.0 10830.9 8256.7 8331.9 8344.0 8362.0 8363.9 5209.9 5655.6 5705.8 5750.8 6017.7 9779.4 9972.0 10022.9 10033.9 10045.9 9617.9 9949.0 10123.4 10148.7 10193.7 6942.9 7163.6 7464.8 7508.8 7747.4 8388.5 8607.3 8616.8 8617.4 8619.6 7500.9 7653.3 7683.9 7745.3 7801.0 7353.4 7508.7 7526.5 7547.7 7691.5 6993.6 7147.5 7156.0 7176.1 7178.5 4973.1 5008.8 5117.5 5275.7 5363.1 4998.3 5109.0 5189.0 5256.8 5297.7 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4630.64 Median:5256.22 stddev:1452.86 (27.6%) stddev-sans-first:1418.89 07:40:06 INFO - PROCESS | 1669 | Values: 229.9 290.0 300.2 313.6 314.6 5245.7 5412.2 5412.7 5480.2 5541.6 4955.6 5101.7 5206.8 5347.5 5420.6 3714.9 4173.5 4183.7 4186.8 4187.2 3757.9 4098.0 4169.9 4175.4 4214.7 3613.4 4054.1 4054.4 4059.9 4060.5 4068.8 4081.3 4082.7 4092.2 4116.7 3133.8 3311.6 3337.3 3511.8 3522.5 5269.4 5311.6 5325.5 5342.3 5343.4 3938.4 4687.2 4691.3 4715.1 4727.5 5163.7 5288.7 5384.6 5642.7 5745.5 3864.9 3868.5 3878.1 3890.1 3902.9 4893.6 5278.3 6045.2 6088.4 6153.4 3642.6 3885.7 3908.3 3938.5 3944.0 3962.8 3966.5 4008.8 4014.0 4023.9 4439.4 4622.5 4677.8 4700.4 4711.8 5433.5 5670.3 5833.4 5836.8 5851.8 1253.0 1273.6 1309.7 1343.9 1349.4 762.5 814.2 861.6 899.6 918.7 4976.8 5361.6 5394.8 5421.9 5423.7 5504.3 5846.8 5871.2 5883.5 5921.6 4582.5 4851.8 5072.7 5378.8 5398.5 2780.4 2844.5 3108.0 3112.9 3133.6 5812.9 5856.0 6150.8 6151.2 6170.6 4717.9 5974.5 6005.7 6084.7 6098.7 6186.7 6194.2 6220.8 6240.4 6253.9 5216.3 5266.8 5775.8 5839.2 5911.6 4738.9 5193.5 5825.3 5850.6 5883.9 5183.4 6124.3 6143.2 6169.3 6214.2 2976.2 3129.1 3617.5 3859.2 3883.8 4937.0 5688.9 5827.4 5858.5 5889.2 4840.6 5509.0 5726.8 5729.9 5760.7 5010.4 5763.3 5781.6 5795.2 5795.9 5326.9 5620.8 5649.5 5682.9 5700.1 5337.2 5477.7 5547.8 5862.4 5893.8 4751.5 4992.4 5039.8 5458.9 5632.5 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:24994.82 Median:3772.16 stddev:40410.14 (1071.3%) stddev-sans-first:40482.33 07:40:06 INFO - PROCESS | 1669 | Values: 700.6 700.6 744.8 778.4 832.9 12778.4 15157.9 15529.6 16030.8 16032.0 14613.3 14633.3 14715.3 14776.7 14780.6 715.3 888.6 898.7 899.5 900.5 705.8 729.8 897.2 906.2 912.4 944.0 964.4 968.0 969.9 972.2 961.9 962.8 962.9 963.6 963.7 684.6 685.2 702.8 715.1 716.0 15100.7 15208.2 15229.9 15243.5 15255.9 13598.2 14604.3 14668.4 14965.0 15139.6 11881.9 11947.0 12001.8 12056.9 12063.1 3751.9 3772.3 3808.4 3832.6 3922.3 109597.0 110130.0 112088.4 113936.0 115942.4 9360.7 11332.0 11408.3 11412.8 11511.9 19493.5 23635.1 24051.0 24103.9 24290.6 8007.4 8030.2 8065.6 8122.4 8208.0 4420.6 4493.6 4519.5 4524.9 4546.1 3180.9 3632.7 3772.1 3788.5 3796.1 528.6 601.0 603.4 644.4 653.8 581.0 590.1 646.6 661.5 680.3 9359.7 9445.0 9514.0 9754.3 9819.7 636.3 666.3 671.5 679.6 684.3 3698.8 4424.7 4675.9 4722.0 4738.8 129026.4 154245.0 161077.2 161233.0 161533.0 100795.5 126378.1 137300.3 137585.0 141591.6 87668.6 94472.5 117728.4 119191.7 119933.8 9032.8 9063.2 9072.7 9135.1 9153.8 61330.7 63550.7 63820.4 67390.7 67919.3 82988.3 87219.4 96719.0 98036.1 103025.5 11902.6 12305.4 12437.6 12504.9 12846.8 12865.5 13117.5 13354.7 13482.7 14138.7 12040.5 12112.1 12122.8 12127.2 12602.1 12378.6 12489.5 12500.7 12507.6 12583.7 12109.3 12152.8 12156.8 12188.3 12234.4 9834.2 10376.6 10558.4 10566.4 11122.9 9987.4 10106.1 10130.4 10170.9 10201.0 07:40:06 INFO - PROCESS | 1669 | -------- Summary: end -------- 07:40:06 INFO - PROCESS | 1669 | 07:40:06 INFO - TEST-INFO | 1669: exit 0 07:40:06 INFO - TEST-OK | dromaeo_css | took 1116696ms 07:40:06 INFO - TEST-START | kraken 07:40:06 INFO - operating with platform_type : mac_ 07:40:06 INFO - Initialising browser for kraken test... 07:40:06 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpeL66rn/profile http://localhost:49276/getInfo.html) 07:40:09 INFO - PROCESS | 1678 | 2016-10-16 07:40:09.697 plugin-container[1679:11290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 07:40:09 INFO - PROCESS | 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:40:09 INFO - PROCESS | 1678 | 2016-10-16 07:40:09.701 plugin-container[1679:11290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 07:40:09 INFO - PROCESS | 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:40:09 INFO - PROCESS | 1678 | 2016-10-16 07:40:09.701 plugin-container[1679:11290] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:40:09 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 07:40:09 INFO - PROCESS | 1678 | colorDepth:24 07:40:09 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 07:40:09 INFO - PROCESS | 1678 | __metrics 07:40:09 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:40:10 INFO - TEST-INFO | 1678: exit 0 07:40:10 INFO - Browser initialized. 07:40:10 INFO - Running cycle 1/1 for kraken test... 07:40:10 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpeL66rn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:40:12 INFO - PROCESS | 1680 | 2016-10-16 07:40:12.929 plugin-container[1681:11410] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 07:40:12 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:40:12 INFO - PROCESS | 1680 | 2016-10-16 07:40:12.929 plugin-container[1681:11410] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 07:40:12 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:40:12 INFO - PROCESS | 1680 | 2016-10-16 07:40:12.929 plugin-container[1681:11410] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:40:22 INFO - PROCESS | 1680 | RSS: Main: 237613056 07:40:22 INFO - PROCESS | 1680 | 07:40:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 07:40:26 INFO - PROCESS | 1680 | RSS: Main: 250109952 07:40:26 INFO - PROCESS | 1680 | 07:40:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 07:40:30 INFO - PROCESS | 1680 | RSS: Main: 258379776 07:40:30 INFO - PROCESS | 1680 | 07:40:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 07:40:34 INFO - PROCESS | 1680 | RSS: Main: 260943872 07:40:34 INFO - PROCESS | 1680 | 07:40:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 07:40:36 INFO - PROCESS | 1680 | RSS: Main: 261693440 07:40:36 INFO - PROCESS | 1680 | 07:40:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:40:39 INFO - PROCESS | 1680 | RSS: Main: 261939200 07:40:39 INFO - PROCESS | 1680 | 07:40:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 07:40:43 INFO - PROCESS | 1680 | RSS: Main: 264089600 07:40:43 INFO - PROCESS | 1680 | 07:40:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 07:40:46 INFO - PROCESS | 1680 | RSS: Main: 264261632 07:40:46 INFO - PROCESS | 1680 | 07:40:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 07:40:50 INFO - PROCESS | 1680 | RSS: Main: 264355840 07:40:50 INFO - PROCESS | 1680 | 07:40:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:40:52 INFO - PROCESS | 1680 | RSS: Main: 264024064 07:40:52 INFO - PROCESS | 1680 | 07:40:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:40:55 INFO - PROCESS | 1680 | RSS: Main: 263757824 07:40:55 INFO - PROCESS | 1680 | 07:40:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:40:58 INFO - PROCESS | 1680 | RSS: Main: 263901184 07:40:58 INFO - PROCESS | 1680 | 07:41:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:41:01 INFO - PROCESS | 1680 | RSS: Main: 264089600 07:41:01 INFO - PROCESS | 1680 | 07:41:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:41:05 INFO - PROCESS | 1680 | RSS: Main: 263995392 07:41:05 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 07:41:07 INFO - PROCESS | 1680 | RSS: Main: 265621504 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | __start_tp_report 07:41:07 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 07:41:07 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 07:41:07 INFO - PROCESS | 1680 | |i|pagename|runs| 07:41:07 INFO - PROCESS | 1680 | |0;ai-astar;117;116;118;119;116;121;118;103;169;175 07:41:07 INFO - PROCESS | 1680 | |1;audio-beat-detection;175;105;145;147;109;129;151;102;181;119 07:41:07 INFO - PROCESS | 1680 | |2;audio-dft;190;215;269;213;199;202;194;229;281;199 07:41:07 INFO - PROCESS | 1680 | |3;audio-fft;82;62;58;85;60;59;53;59;88;83 07:41:07 INFO - PROCESS | 1680 | |4;audio-oscillator;87;71;86;74;69;71;87;70;73;92 07:41:07 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;89;77;136;125;137;131;92;87;90;86 07:41:07 INFO - PROCESS | 1680 | |6;imaging-darkroom;99;100;110;99;101;99;99;100;99;135 07:41:07 INFO - PROCESS | 1680 | |7;imaging-desaturate;89;90;87;87;88;87;113;77;135;142 07:41:07 INFO - PROCESS | 1680 | |8;json-parse-financial;58;59;60;61;68;68;94;90;85;62 07:41:07 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;65;73;94;75;64;58;64;75;100;102 07:41:07 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;113;114;112;107;87;90;88;87;86;88 07:41:07 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;145;149;150;163;159;166;158;160;199;177 07:41:07 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;167;188;176;175;179;177;173;177;176;172 07:41:07 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;62;61;63;89;91;87;93;92;91;69 07:41:07 INFO - PROCESS | 1680 | __end_tp_report 07:41:07 INFO - PROCESS | 1680 | __start_cc_report 07:41:07 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,948 07:41:07 INFO - PROCESS | 1680 | __end_cc_report 07:41:07 INFO - PROCESS | 1680 | __startTimestamp1476628867511__endTimestamp 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | ------- Summary: start ------- 07:41:07 INFO - PROCESS | 1680 | Number of tests: 14 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:127.20 Median:118.50 stddev:24.15 (20.4%) stddev-sans-first:25.33 07:41:07 INFO - PROCESS | 1680 | Values: 117.0 116.0 118.0 119.0 116.0 121.0 118.0 103.0 169.0 175.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:146.00 stddev:28.20 (19.3%) stddev-sans-first:26.20 07:41:07 INFO - PROCESS | 1680 | Values: 175.0 105.0 145.0 147.0 109.0 129.0 151.0 102.0 181.0 119.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:219.10 Median:214.00 stddev:31.71 (14.8%) stddev-sans-first:31.84 07:41:07 INFO - PROCESS | 1680 | Values: 190.0 215.0 269.0 213.0 199.0 202.0 194.0 229.0 281.0 199.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:68.90 Median:72.00 stddev:13.70 (19.0%) stddev-sans-first:13.68 07:41:07 INFO - PROCESS | 1680 | Values: 82.0 62.0 58.0 85.0 60.0 59.0 53.0 59.0 88.0 83.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:80.00 stddev:8.86 (11.1%) stddev-sans-first:8.77 07:41:07 INFO - PROCESS | 1680 | Values: 87.0 71.0 86.0 74.0 69.0 71.0 87.0 70.0 73.0 92.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:86.50 stddev:23.99 (27.7%) stddev-sans-first:24.74 07:41:07 INFO - PROCESS | 1680 | Values: 89.0 77.0 136.0 125.0 137.0 131.0 92.0 87.0 90.0 86.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:99.00 stddev:11.37 (11.5%) stddev-sans-first:11.91 07:41:07 INFO - PROCESS | 1680 | Values: 99.0 100.0 110.0 99.0 101.0 99.0 99.0 100.0 99.0 135.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:99.50 Median:87.00 stddev:22.50 (25.9%) stddev-sans-first:23.54 07:41:07 INFO - PROCESS | 1680 | Values: 89.0 90.0 87.0 87.0 88.0 87.0 113.0 77.0 135.0 142.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:70.50 Median:68.00 stddev:13.81 (20.3%) stddev-sans-first:13.89 07:41:07 INFO - PROCESS | 1680 | Values: 58.0 59.0 60.0 61.0 68.0 68.0 94.0 90.0 85.0 62.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:77.00 Median:69.00 stddev:16.02 (23.2%) stddev-sans-first:16.39 07:41:07 INFO - PROCESS | 1680 | Values: 65.0 73.0 94.0 75.0 64.0 58.0 64.0 75.0 100.0 102.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:87.00 stddev:12.48 (14.3%) stddev-sans-first:11.85 07:41:07 INFO - PROCESS | 1680 | Values: 113.0 114.0 112.0 107.0 87.0 90.0 88.0 87.0 86.0 88.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:162.60 Median:161.50 stddev:15.77 (9.8%) stddev-sans-first:15.39 07:41:07 INFO - PROCESS | 1680 | Values: 145.0 149.0 150.0 163.0 159.0 166.0 158.0 160.0 199.0 177.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:176.50 stddev:5.40 (3.1%) stddev-sans-first:4.64 07:41:07 INFO - PROCESS | 1680 | Values: 167.0 188.0 176.0 175.0 179.0 177.0 173.0 177.0 176.0 172.0 07:41:07 INFO - PROCESS | 1680 | 07:41:07 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:90.00 stddev:14.06 (15.6%) stddev-sans-first:13.36 07:41:07 INFO - PROCESS | 1680 | Values: 62.0 61.0 63.0 89.0 91.0 87.0 93.0 92.0 91.0 69.0 07:41:07 INFO - PROCESS | 1680 | -------- Summary: end -------- 07:41:07 INFO - PROCESS | 1680 | 07:41:08 INFO - TEST-INFO | 1680: exit 0 07:41:08 INFO - TEST-OK | kraken | took 61718ms 07:41:08 INFO - SUITE-END | took 1178s 07:41:08 INFO - Completed test suite (00:19:38) 07:41:08 INFO - javascript benchmark 07:41:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7282.19967413101, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.1318257253124, 3379.4254976653965, 3405.5402520659704, 3415.265717870659, 3417.1798768295616, 5600.52395546378, 5605.943940560594, 5652.576056795741, 5664.518515926151, 5746.48281654651, 5630.521405680512, 5697.77208911643, 5703.914441283374, 5749.511291540213, 5783.942160578393, 5179.585633149358, 5188.4811518848, 5256.3955145158225, 5303.814366497173, 5315.7873685052555, 5207.583393328544, 5325.440828712987, 5342.759575819097, 5366.651167674088, 5408.783648654049, 5105.438401775802, 5111.080005599028, 5187.507186817246, 5220.3996540397775, 5275.472452754732, 5168.664036837594, 5232.215167724872, 5251.501107394792, 5259.000789849916, 5295.338082739658, 5177.45636708144, 5177.870553236162, 5185.040767457995, 5186.611004174702, 5223.921641175385, 4050.4936882889638, 5254.607529004817, 5553.583481238883, 5738.282714660668, 5740.6268592541355, 5183.403908550527, 5589.0, 5624.67820062285, 6140.508759299245, 6224.09750586167, 6254.030625253093, 6261.499080073583, 6266.279377871558, 6269.310375858652, 6292.0, 5458.972705136449, 5757.222774925415, 5770.278715160605, 5793.008814799759, 5847.327557330961, 5550.639208451437, 6119.502553941423, 6172.259328880563, 6191.4118158774845, 6212.596181248295, 6117.694115294217, 6157.138000679908, 6165.815025549241, 6165.938340616626, 6172.166757487771, 5625.296837895263, 6098.522998457079, 6215.844603884939, 6240.095186198067, 6300.0, 5109.770060347293, 6115.605440958429, 6128.19758601264, 6200.658963757037, 6276.403741644628, 6213.2233470816145, 6269.749210031548, 6281.654509001957, 6284.402981716729, 6306.873862522724, 4952.356193694796, 5017.347744793226, 5018.774155163026, 5022.89954200914, 5053.9241911371355, 4364.345348197732, 4376.934345984812, 4411.228035093845, 4465.084657645175, 4523.705959112647, 5902.704864756745, 5910.852228694317, 5956.0, 5961.940380596138, 5973.671448070311, 5277.313949186581, 6250.6874656267, 6268.529860260499, 6271.435570798559, 6273.12176295319, 5717.42825717425, 5789.884202316014, 5792.623479473821, 5802.361740208574, 5938.168656388109, 5395.568354531707, 5427.402985671573, 5434.619576629677, 5449.264349312872, 5482.588805839578, 6323.873522529616, 6333.778317758856, 6337.302896681362, 6357.745690172342, 6373.8725225496155, 6133.509319254538, 6151.323354431099, 6182.69086545662, 6206.348333425106, 6231.470325022424, 6288.056791481315, 6364.77723279683, 6420.582662126947, 6426.228852537725, 6437.905556055575, 6174.320824709189, 6239.0, 6255.812325630239, 6270.435660790551, 6272.937270627235, 6124.846878828065, 6206.162168107247, 6294.1188233648245, 6304.684765761602, 6307.842303942438, 5919.348871624204, 6060.2424696912885, 6100.944524685144, 6181.907271391023, 6184.257889053342, 6092.939070609237, 6141.6007959482495, 6146.631202127886, 6150.876982460415, 6181.96909015452, 4995.300657907968, 5824.15549745293, 5847.181394604674, 5883.35283118848, 6057.242844644419, 6097.887666312708, 6110.236220472441, 6119.051547010221, 6137.048757442603, 6139.754409823557, 5949.077892926603, 5971.193888824953, 5982.4316689914385, 5984.251968503937, 5987.850303742442], "value": 5677.465481004804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.81498992907876, 531.500389633744, 533.1176902226815, 539.4093467652921, 542.8561431220725, 39149.412758808605, 40295.597044029586, 40589.59410405899, 40670.79664601673, 40685.98285042875, 36972.81513592435, 42606.147877042444, 42759.3586096208, 42930.356044659275, 42932.0, 9491.572879220452, 9541.618335266616, 9668.613255469772, 9673.322867399285, 9767.462789546573, 9443.763905902373, 9486.098820612031, 9525.380850244746, 9527.475988820612, 9537.618495260216, 9595.328327017112, 9602.079584083176, 9605.711828645151, 9615.95192024039, 9689.903100969006, 9547.283953703465, 9562.904370956307, 9567.665131720427, 9569.569369378394, 9607.231421486264, 8277.130901255341, 9380.0, 9460.195883349923, 9563.043695630451, 9587.0, 32693.18267043319, 39947.403418777976, 40019.99950001244, 40355.59644403577, 40602.79698601518, 3503.474478828196, 3543.911402214965, 3570.2145527983803, 3897.4933258676474, 4033.6369726724733, 30362.93729719449, 30695.69304306973, 31189.37621247585, 31450.37099258025, 31989.880354187724, 3161.1148878314107, 3186.5857438533076, 3201.0, 3211.454052811028, 3263.820489873056, 494135.0, 495957.0, 501616.0, 503112.0, 503427.99999999633, 58129.0, 58299.70850145722, 58302.70848645814, 58363.0, 58518.707406462694, 59991.70004149951, 60727.0, 61150.69424652848, 61207.387926120166, 61286.080708789406, 16465.67068658644, 16687.165641717864, 16765.58086047834, 16786.160691965353, 16786.244618992176, 13014.739705206031, 13020.934895325652, 13127.934360328138, 13134.211947283193, 13154.947604191642, 15211.08733475995, 15515.836312276624, 15557.14435706047, 15603.921980390025, 15639.530814075595, 4767.475577686451, 4804.615630749532, 5249.42256351801, 5335.519803217729, 6064.605800623033, 723.3074605423335, 906.7914379692708, 933.2580598424269, 1122.3939072900705, 1177.270092542629, 27160.0, 28646.427071458453, 28727.856360718062, 30613.540796888065, 30617.16297022186, 991.4943378876825, 1070.4861666400016, 1073.5759375046812, 1075.671920064388, 1110.1119104716356, 15572.159256893554, 15629.76555351648, 15661.765073523906, 15816.446424375092, 15963.36146554148, 546508.0, 625101.0, 633378.0, 633762.0, 634093.0, 58453.41546584565, 58475.83048339009, 58476.83046339134, 58550.7072464635, 58658.12012819811, 1348.8044233586272, 1392.1368751374214, 1414.8514405987428, 1415.0094933546352, 1415.405529677538, 23755.179980904788, 30668.693313066582, 31367.90212342557, 32077.198070048435, 32637.836810815796, 48379.03241935071, 56773.0, 57200.71399642975, 57322.0, 57403.0, 570935.0, 572527.0, 578071.0, 578877.0, 580017.0, 25631.23106306814, 25740.0, 25842.483150336513, 25858.094966676075, 25877.741222587534, 47158.82102947454, 47187.82030449266, 48143.27785083157, 48655.0, 49214.753926230136, 39655.60344396519, 39764.602353976086, 39887.800560997006, 39912.201755965296, 42318.57681423146, 39230.0, 39586.604133959445, 40095.0, 40538.1892362157, 40795.38806917958, 32801.34397312088, 33336.666633333356, 39025.414618780174, 39201.019974500865, 39278.01804954899, 29674.25814354572, 32696.836515817267, 33599.65601375917, 34981.47527787034, 35808.46287305641, 25435.74564254408, 25521.61717574275, 25764.742352576235, 25879.22362329133, 26413.60379594346], "value": 19764.33585156044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.9773915825892, 340.50796598914565, 376.16116061183567, 377.8639689711705, 402.376316709101, 19634.0, 20978.89510552447, 21222.57554848902, 21264.89367553164, 21479.463013424673, 20265.189392424287, 20717.0, 20801.27195548156, 21021.68467472985, 21278.0, 5016.673916195454, 5373.650712703681, 5463.972680136594, 5539.169124631298, 5731.971340143315, 5651.2653355063785, 5711.314642242936, 5751.367349591542, 5755.963926493229, 5771.22088518051, 4696.953030469703, 5544.972275138619, 5593.161025846115, 5594.9720251398885, 5716.22830917827, 5644.238027866227, 5649.0114230009785, 5656.886862262772, 5690.317161940573, 5693.3452652945025, 4770.642201834877, 4936.48166942473, 4945.332380128709, 5004.074246264453, 5013.699178049329, 24912.377190570198, 25299.114530991326, 25366.492670146494, 25475.980960761546, 25676.48647027049, 2206.2829580386438, 2281.0533628544135, 2407.4222186675247, 2411.3127758588894, 2697.5414179589516, 15331.923340383339, 15434.0, 15566.455174068853, 15960.920195398949, 15969.281382337707, 1780.2700892634086, 1801.2524802207067, 1830.7436958825774, 1853.2309091726918, 1853.2309091727052, 102440.97559024389, 102709.48645256876, 103752.0, 104025.4798726009, 104363.47818260935, 9318.0, 9577.425354478753, 9722.451305273524, 9878.654247101316, 10913.672589822318, 11180.944095279472, 12044.939775301244, 12460.0, 13035.0, 13765.793513097311, 8586.828263434696, 8618.095100014534, 8690.348223883235, 8712.869306960401, 8905.688300909436, 7181.245969173162, 8731.869021964676, 8739.082396348418, 8795.164459376349, 8868.0, 6519.478441724651, 6630.403263706289, 6659.733610655519, 6672.2994085621285, 6672.966635166793, 1606.967860642804, 1853.684873571469, 1874.6344462829618, 1885.4249453916414, 1939.4375631067114, 696.905917701114, 780.9574218418361, 901.1844280925773, 917.5870858113875, 1057.1754031855164, 19390.4182874514, 20575.897120514303, 22110.77889221087, 22313.107475700792, 22357.329280121623, 410.5914614958136, 413.4893406642794, 426.11368353823974, 430.7014351729538, 431.37450696490214, 10766.354018758899, 11554.191206615456, 11618.76762464746, 12106.010787829393, 12128.090393220544, 582824.0, 595224.0, 598411.0, 601407.0, 609657.0, 121526.0, 133074.0, 137596.0, 140165.2991735055, 150978.0, 421.5131523090774, 440.79943625650253, 445.09423323536447, 445.730333148447, 447.5792754810533, 9724.659636912957, 10707.892921071001, 11029.338239705643, 11316.038136758467, 11535.480903359201, 53696.0, 55055.0, 56254.71872640611, 56826.14760778509, 57733.42266577281, 74042.0, 75745.0, 80732.78900816375, 84816.0, 84852.57573712092, 7560.319571238615, 7575.583342916192, 7932.524048557105, 8069.103760616368, 8198.303144232807, 10676.73308167271, 11850.288982661068, 12153.0, 12804.551840685532, 13183.934080329536, 10474.423906685206, 11025.724356891142, 11184.21710480291, 11551.075913927034, 11667.416629168338, 11115.944420277847, 11156.609518666808, 11196.216264861707, 11467.598634048101, 11488.712782180512, 11326.433678315887, 11458.083353331547, 11856.22934509254, 11939.582114625946, 12004.339761313211, 6725.529212955046, 6944.375006249462, 8150.551719655238, 8236.299914507093, 8391.412601117863, 10729.731756706145, 10925.726856828644, 11027.83458248111, 11335.546578136782, 11393.829092563452], "value": 9061.198926566092, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.417581703593, 1479.8372179060304, 1526.2979029646363, 1785.2055835153353, 1937.9321723739672, 7862.488938219026, 8209.0, 8528.317734581235, 9065.093490650932, 9170.220531254843, 8545.829083418328, 8843.646254149826, 8876.95561522193, 9006.729798106051, 9012.0, 2366.065404165358, 2367.775061369176, 2368.692070030894, 2376.1327115602853, 2381.5475059738596, 2352.2590384029063, 2364.8935797889135, 2399.436132508859, 2401.627747699101, 2416.8429052111555, 2373.1456675596773, 2380.119355838342, 2392.162743039931, 2401.327628264089, 2405.891734871926, 1839.3010655950786, 2049.405672355015, 2330.4057465346314, 2347.6713260143592, 2386.2721869829693, 1980.5345743750206, 2351.341624345169, 2352.5765362234793, 2353.341064501942, 2359.197872723282, 8925.955370223108, 9127.40671856334, 9134.31492638048, 9135.177833994972, 9162.31282653797, 5286.5770738341225, 5369.301990741179, 5468.0977638689565, 5469.535089517396, 5480.095784195603, 3476.148343655795, 3545.8936231913085, 3665.963340366589, 3667.908302292464, 3677.797721125337, 1012.6202673997251, 1050.5220124843179, 1051.6529545250125, 1058.8358804385332, 1079.6545105566222, 10540.947295263475, 10837.891621083845, 11799.705007374798, 11866.228695134789, 11903.226290291102, 4536.844998625626, 4648.418947631546, 4783.808647654125, 4854.2233242681705, 4877.463479017305, 8711.651533938571, 10148.98510148994, 10180.032896874785, 10194.235432342455, 10195.949020254851, 5330.440303768049, 5382.300300960928, 5390.164524498709, 5402.756875940592, 5405.135178371442, 4437.134758722018, 4605.746683932349, 4741.38362012943, 4875.926861097087, 4915.557599815962, 3913.2173565287057, 3951.565327813938, 4025.3156963316305, 4048.7975601219823, 4087.3664581989838, 1287.6909541709922, 1302.6222395505388, 1304.9869501305054, 1307.1307580458888, 1311.8556958734532, 930.5858892792643, 933.1554942776797, 934.6914319952122, 952.1716106986966, 986.7237173591406, 5953.434423729739, 6002.4897883679505, 6058.454739073504, 6059.27288725347, 6083.908741368883, 415.93137132373425, 421.084141991168, 431.91577642359437, 442.9446319210099, 450.9492881585897, 4003.259397011562, 4011.6991225658194, 4012.8194231259663, 4145.253854306254, 4147.2534943710425, 10484.842727359095, 10720.946395268129, 10830.945845270724, 10850.023497885228, 10869.782604348027, 10782.867798881005, 10790.406527641055, 10800.891991079989, 10808.97314755097, 10830.945845270724, 8256.711015114442, 8331.875021874677, 8343.95828020868, 8362.038365588036, 8363.874541881876, 5209.921851172311, 5655.632383895034, 5705.800296989585, 5750.7987220447085, 6017.699115044143, 9779.364341317792, 9972.00279972026, 10022.949885250528, 10033.949830250802, 10045.899541004497, 9617.855732164164, 9949.0, 10123.392596444237, 10148.695539133838, 10193.694189174337, 6942.861142777015, 7163.606001669749, 7464.776056718307, 7508.849823003618, 7747.418943579141, 8388.454750441202, 8607.268382187583, 8616.78458038554, 8617.439866408664, 8619.569021549023, 7500.887486687595, 7653.3494652952895, 7683.884741728991, 7745.341645960157, 7801.0, 7353.448491363062, 7508.699652013858, 7526.473146879666, 7547.698092076255, 7691.4615976883315, 6993.580385176906, 7147.499675022698, 7156.033935418652, 7176.067111275501, 7178.497505174588, 4973.104841128632, 5008.799648014185, 5117.5394214520875, 5275.709835959059, 5363.088274993339, 4998.275250088789, 5108.974455127701, 5189.0, 5256.816011439581, 5297.655652382583], "value": 4531.174462907682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [229.87816457277637, 289.98840046398146, 300.22242392204214, 313.58606639236206, 314.58631899052756, 5245.659032162916, 5412.161115027167, 5412.702301373423, 5480.246321177225, 5541.556675465963, 4955.578775804061, 5101.7194054327, 5206.7917283308625, 5347.465253474651, 5420.566354691625, 3714.9071273218115, 4173.5409104998425, 4183.686223533247, 4186.79066046698, 4187.204431158086, 3757.8872633820893, 4098.0, 4169.916601667965, 4175.436316097318, 4214.726042807208, 3613.385724426854, 4054.128362402083, 4054.3918412238254, 4059.939100913489, 4060.4924384451942, 4068.7965601719943, 4081.265372232997, 4082.6937979651643, 4092.1815636872743, 4116.670666346685, 3133.764967627414, 3311.5760223104057, 3337.2658015236852, 3511.8059859647587, 3522.5068490411363, 5269.420363759983, 5311.62818602698, 5325.520703136736, 5342.332208473941, 5343.412224655285, 3938.448617193595, 4687.20317546018, 4691.319758634979, 4715.127701375222, 4727.5272472752795, 5163.657449063233, 5288.709120998344, 5384.6499977501335, 5642.6614403135945, 5745.48290653839, 3864.942025869614, 3868.458415821787, 3878.0692633767976, 3890.074413165473, 3902.863399780994, 4893.60851131905, 5278.287431196778, 6045.153678485016, 6088.4215999479975, 6153.415425534612, 3642.6357364263363, 3885.6891448684037, 3908.335582950848, 3938.542739186519, 3944.0, 3962.841486340514, 3966.484357033567, 4008.7594744315434, 4013.979930100331, 4023.8994025149605, 4439.378487011821, 4622.537746225351, 4677.836275730333, 4700.37836946253, 4711.764411779397, 5433.483819037185, 5670.291213598301, 5833.445822646841, 5836.824895253149, 5851.824445266649, 1252.9624111276676, 1273.605182393461, 1309.6594885329691, 1343.932803359828, 1349.406261245049, 762.4777027736019, 814.1776805426562, 861.6208868098017, 899.6356475627381, 918.6555041859302, 4976.776045077981, 5361.597880159004, 5394.78420863161, 5421.939662928062, 5423.694929654881, 5504.256925315111, 5846.766129354778, 5871.178035075093, 5883.499902508249, 5921.644701317935, 4582.450105987303, 4851.792434442391, 5072.731817045738, 5378.772736358232, 5398.544931243929, 2780.430011847566, 2844.4737723520802, 3107.9689203107678, 3112.937741245162, 3133.6082989626298, 5812.854678632983, 5855.950417353892, 6150.7847225346895, 6151.200343955346, 6170.598911070766, 4717.90053426222, 5974.492168165668, 6005.729742161613, 6084.665343406066, 6098.69506524672, 6186.659733714599, 6194.163787888578, 6220.844478887973, 6240.4071613196675, 6253.906191407224, 5216.269722238813, 5266.78932842682, 5775.797847075332, 5839.2190381933915, 5911.645301281937, 4738.928916066331, 5193.532582067632, 5825.330087039875, 5850.619709718854, 5883.852903677443, 5183.403908550414, 6124.284301088226, 6143.170671959407, 6169.334279744534, 6214.161088253028, 2976.2410585301477, 3129.123845323313, 3617.4987380236907, 3859.150986782903, 3883.776610367725, 4936.950630493793, 5688.857778555569, 5827.388124246893, 5858.47273745365, 5889.234399528034, 4840.6127509799835, 5508.9724551376985, 5726.770929162787, 5729.885402291848, 5760.740766665426, 5010.398752149766, 5763.279590051244, 5781.566382521243, 5795.217645617788, 5795.8551036224435, 5326.866828329323, 5620.831375058755, 5649.519790817826, 5682.943170568242, 5700.144978253129, 5337.172738225581, 5477.72611369422, 5547.805826796042, 5862.355140934408, 5893.764249429974, 4751.477337492804, 4992.400911890451, 5039.798408063783, 5458.935507575985, 5632.49307562305], "value": 4153.651170412127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.6426722371591, 700.6496751624192, 744.7802898145046, 778.3889646627399, 832.9042160151582, 12778.361081945912, 15157.924210378946, 15529.611759706013, 16030.75953860693, 16031.957922735013, 14613.342399592017, 14633.341499632515, 14715.26423678817, 14776.743976761989, 14780.556583302492, 715.3383120613412, 888.5868071346839, 898.6944438890775, 899.491787140267, 900.4642237868458, 705.8023753349071, 729.7555318968125, 897.1880448194395, 906.2387594420685, 912.4434095201922, 944.0465130218495, 964.4406244378243, 967.9806403871918, 969.8787651543557, 972.1687956796943, 961.9134277914986, 962.8026254617787, 962.9229661627053, 963.648268382039, 963.6627180486844, 684.5721424109931, 685.1640997982454, 702.8137543550963, 715.1275443958363, 716.0297796485739, 15100.697986040217, 15208.16355100469, 15229.923850380788, 15243.542693719206, 15255.923720381326, 13598.184108953395, 14604.269786510631, 14668.413263469449, 14965.0, 15139.621509462351, 11881.94059029708, 11947.0, 12001.819972700416, 12056.879431205663, 12063.095267854858, 3751.924961500782, 3772.264408440355, 3808.371618682914, 3832.5784163741964, 3922.2743792398496, 109597.0, 110130.0, 112088.43955780168, 113936.0, 115942.42028789801, 9360.672376466791, 11332.036776874058, 11408.315501069963, 11412.775985244369, 11511.90636889494, 19493.512662183275, 23635.054597815895, 24051.0, 24103.879480602485, 24290.635640465407, 8007.399445041528, 8030.196980301923, 8065.556394398247, 8122.350211983027, 8208.015038195455, 4420.646348292129, 4493.550644935545, 4519.502854685982, 4524.909501809945, 4546.090781843578, 3180.9204769880475, 3632.6730594246114, 3772.0569857535615, 3788.450674652215, 3796.126890815128, 528.5718567959965, 601.0323379359228, 603.4418163199024, 644.3846126948753, 653.8365408647838, 580.9608274954938, 590.1472372421895, 646.6249575246346, 661.4873473058323, 680.2789043613785, 9359.672411465566, 9445.008274131254, 9513.952430237805, 9754.317197796086, 9819.705408837746, 636.3350298937529, 666.277089358052, 671.4998811504585, 679.6431873266574, 684.2815044203567, 3698.815059247027, 4424.668149888771, 4675.883102922386, 4722.0, 4738.786754596052, 129026.35486822693, 154245.0, 161077.19461402617, 161233.0, 161533.0, 100795.49602252088, 126378.10432843515, 137300.31349843385, 137585.0, 141591.58408415783, 87668.56165719131, 94472.52763736137, 117728.41135794438, 119191.67679070475, 119933.80066199401, 9032.774180645536, 9063.229625481908, 9072.682456114004, 9135.08453986011, 9153.771155720893, 61330.69334653298, 63550.68224658847, 63820.404489888126, 67390.66304668445, 67919.3208067913, 82988.34023319378, 87219.38341849754, 96719.0, 98036.07855685978, 103025.48487257515, 11902.642920712393, 12305.44625491873, 12437.62687119388, 12504.874951250371, 12846.807297890726, 12865.549705760253, 13117.540886068942, 13354.666133346744, 13482.662933426744, 14138.717225655224, 12040.458179381774, 12112.093097656323, 12122.818157727817, 12127.215901492416, 12602.054845886412, 12378.62864114078, 12489.500419983098, 12500.687482813002, 12507.56223532172, 12583.68540786451, 12109.33398663082, 12152.817707734568, 12156.756864862477, 12188.268703877795, 12234.388280585757, 9834.164096051916, 10376.636817711344, 10558.419286938984, 10566.366018038942, 11122.944385278022, 9987.350822196535, 10106.14097801695, 10130.392176469435, 10170.949145254226, 10201.030902064293], "value": 7792.334819938224, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 119.0, 116.0, 121.0, 118.0, 103.0, 169.0, 175.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 105.0, 145.0, 147.0, 109.0, 129.0, 151.0, 102.0, 181.0, 119.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 215.0, 269.0, 213.0, 199.0, 202.0, 194.0, 229.0, 281.0, 199.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 58.0, 85.0, 60.0, 59.0, 53.0, 59.0, 88.0, 83.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 74.0, 69.0, 71.0, 87.0, 70.0, 73.0, 92.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 77.0, 136.0, 125.0, 137.0, 131.0, 92.0, 87.0, 90.0, 86.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 110.0, 99.0, 101.0, 99.0, 99.0, 100.0, 99.0, 135.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 87.0, 87.0, 88.0, 87.0, 113.0, 77.0, 135.0, 142.0], "value": 99.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 60.0, 61.0, 68.0, 68.0, 94.0, 90.0, 85.0, 62.0], "value": 70.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 94.0, 75.0, 64.0, 58.0, 64.0, 75.0, 100.0, 102.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 112.0, 107.0, 87.0, 90.0, 88.0, 87.0, 86.0, 88.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 149.0, 150.0, 163.0, 159.0, 166.0, 158.0, 160.0, 199.0, 177.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 188.0, 176.0, 175.0, 179.0, 177.0, 173.0, 177.0, 176.0, 172.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 89.0, 91.0, 87.0, 93.0, 92.0, 91.0, 69.0], "value": 79.8, "unit": "score"}]}]} 07:41:08 INFO - Return code: 0 07:41:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:41:08 ERROR - # TBPL SUCCESS # 07:41:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:41:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:41:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:41:08 INFO - 2016-10-16 07:41:08.828 screenresolution[1685:11799] starting screenresolution argv=screenresolution get 07:41:08 INFO - 2016-10-16 07:41:08.831 screenresolution[1685:11799] Display 0: 1600x1200x32@60 07:41:08 INFO - 2016-10-16 07:41:08.837 screenresolution[1686:11802] starting screenresolution argv=screenresolution list 07:41:08 INFO - Available Modes on Display 0 07:41:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:41:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:41:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:41:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:41:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:41:09 INFO - Intel Iris: 07:41:09 INFO - Chipset Model: Intel Iris 07:41:09 INFO - Type: GPU 07:41:09 INFO - Bus: Built-In 07:41:09 INFO - VRAM (Dynamic, Max): 1536 MB 07:41:09 INFO - Vendor: Intel (0x8086) 07:41:09 INFO - Device ID: 0x0a2e 07:41:09 INFO - Revision ID: 0x0009 07:41:09 INFO - Displays: 07:41:09 INFO - Display: 07:41:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:41:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:41:09 INFO - Display Serial Number: 1600x1200 60 07:41:09 INFO - Main Display: Yes 07:41:09 INFO - Mirror: Off 07:41:09 INFO - Online: Yes 07:41:09 INFO - Rotation: Supported 07:41:09 INFO - Return code: 0 07:41:09 INFO - Running post-action listener: _resource_record_post_action 07:41:09 INFO - [mozharness: 2016-10-16 14:41:09.084491Z] Finished run-tests step (success) 07:41:09 INFO - Running post-run listener: _resource_record_post_run 07:41:09 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 32924160; Write bytes: 712485888; Read time: 213; Write time: 2034 07:41:09 INFO - TinderboxPrint: CPU usage
54.5% 07:41:09 INFO - TinderboxPrint: I/O read bytes / time
32,924,160 / 213 07:41:09 INFO - TinderboxPrint: I/O write bytes / time
712,485,888 / 2,034 07:41:09 INFO - TinderboxPrint: CPU idle
2,206.1 (45.5%) 07:41:09 INFO - TinderboxPrint: CPU system
985.9 (20.3%) 07:41:09 INFO - TinderboxPrint: CPU user
1,661.9 (34.2%) 07:41:09 INFO - TinderboxPrint: Swap in / out
437,587,968 / 0 07:41:09 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395035136; Write bytes: 387938304; Read time: 29258; Write time: 787 07:41:09 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 32002560; Write bytes: 299986432; Read time: 202; Write time: 1177 07:41:09 INFO - Running post-run listener: _upload_blobber_files 07:41:09 INFO - Blob upload gear active. 07:41:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:41:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:41:09 INFO - (blobuploader) - INFO - Open directory for files ... 07:41:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:41:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:11 INFO - (blobuploader) - INFO - Done attempting. 07:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:12 INFO - (blobuploader) - INFO - Done attempting. 07:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:14 INFO - (blobuploader) - INFO - Done attempting. 07:41:14 INFO - (blobuploader) - INFO - Iteration through files over. 07:41:14 INFO - Return code: 0 07:41:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:41:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:41:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e4bb8b56713b73c3b1bb772b5be1943c15227b02c9a33b0d3f49bc8d3283cab76c107665d34c2d4133661f9c85a9b02a9ed6e718a9182b17ff4badeae279ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72ff95b6171212749584e48f2db746447218935aa784d371dce70d6e117682e18dcd45e272d05f6fe9d82711b19356d2acd3384acba1b5419f5c98c93db5afc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/531c8534e739f207a3ea831fd4bb56a5855346d7e18aa758c0b067b5e80b9db986fb320d98017ee9c90031ab995434d61d858f215964fbf4aac5b7a9da2e741e"} 07:41:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:41:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:41:14 INFO - Contents: 07:41:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e4bb8b56713b73c3b1bb772b5be1943c15227b02c9a33b0d3f49bc8d3283cab76c107665d34c2d4133661f9c85a9b02a9ed6e718a9182b17ff4badeae279ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72ff95b6171212749584e48f2db746447218935aa784d371dce70d6e117682e18dcd45e272d05f6fe9d82711b19356d2acd3384acba1b5419f5c98c93db5afc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/531c8534e739f207a3ea831fd4bb56a5855346d7e18aa758c0b067b5e80b9db986fb320d98017ee9c90031ab995434d61d858f215964fbf4aac5b7a9da2e741e"} 07:41:14 INFO - Running post-run listener: copy_logs_to_upload_dir 07:41:14 INFO - Copying logs to upload dir... 07:41:14 INFO - mkdir: /builds/slave/test/build/upload/logs 07:41:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.008682 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-16 07:41:14.232976) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 07:41:14.236249) ========= 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.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e4bb8b56713b73c3b1bb772b5be1943c15227b02c9a33b0d3f49bc8d3283cab76c107665d34c2d4133661f9c85a9b02a9ed6e718a9182b17ff4badeae279ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72ff95b6171212749584e48f2db746447218935aa784d371dce70d6e117682e18dcd45e272d05f6fe9d82711b19356d2acd3384acba1b5419f5c98c93db5afc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/531c8534e739f207a3ea831fd4bb56a5855346d7e18aa758c0b067b5e80b9db986fb320d98017ee9c90031ab995434d61d858f215964fbf4aac5b7a9da2e741e"} build_url:https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/PJHiAtZwQkSkpxCrk71XIg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e4bb8b56713b73c3b1bb772b5be1943c15227b02c9a33b0d3f49bc8d3283cab76c107665d34c2d4133661f9c85a9b02a9ed6e718a9182b17ff4badeae279ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72ff95b6171212749584e48f2db746447218935aa784d371dce70d6e117682e18dcd45e272d05f6fe9d82711b19356d2acd3384acba1b5419f5c98c93db5afc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/531c8534e739f207a3ea831fd4bb56a5855346d7e18aa758c0b067b5e80b9db986fb320d98017ee9c90031ab995434d61d858f215964fbf4aac5b7a9da2e741e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 07:41:14.269017) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:41:14.269341) ========= 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.gdgmovStoX/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.TL62PJgcUp/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.004646 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 07:41:14.317752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 07:41:14.318092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 07:41:14.318443) ========= ========= Total master_lag: 0.21 =========