builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0360 starttime: 1463774713.54 results: success (0) buildid: 20160520105741 builduid: 77153f17d4a54554ba738427aa2c8d92 revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.540989) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.541898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.542172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.688301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.688672) ========= 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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-05-20 13:05:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.07M=0.001s 2016-05-20 13:05:13 (8.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125382 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.838435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.838740) ========= 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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.019796 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.887281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 13:05:13.887616) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-05-20 13:05:13,948 truncating revision to first 12 chars 2016-05-20 13:05:13,948 Setting DEBUG logging. 2016-05-20 13:05:13,948 attempt 1/10 2016-05-20 13:05:13,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b4ae8c5edb7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 13:05:15,023 unpacking tar archive at: larch-b4ae8c5edb7e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328174 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 13:05:15.233499) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.233816) ========= script_repo_revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.234175) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.234440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.248661) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-20 13:05:15.248969) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:15 INFO - MultiFileLogger online at 20160520 13:05:15 in /builds/slave/test 13:05:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 13:05:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:05:15 INFO - {'append_to_log': False, 13:05:15 INFO - 'base_work_dir': '/builds/slave/test', 13:05:15 INFO - 'blob_upload_branch': 'Larch', 13:05:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:05:15 INFO - 'branch': 'Larch', 13:05:15 INFO - 'buildbot_json_path': 'buildprops.json', 13:05:15 INFO - 'config_files': ('talos/mac_config.py',), 13:05:15 INFO - 'default_actions': ('clobber', 13:05:15 INFO - 'read-buildbot-config', 13:05:15 INFO - 'download-and-extract', 13:05:15 INFO - 'populate-webroot', 13:05:15 INFO - 'create-virtualenv', 13:05:15 INFO - 'install', 13:05:15 INFO - 'run-tests'), 13:05:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:05:15 INFO - 'download_minidump_stackwalk': True, 13:05:15 INFO - 'download_symbols': 'ondemand', 13:05:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:05:15 INFO - 'tooltool.py': '/tools/tooltool.py', 13:05:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:05:15 INFO - '/tools/misc-python/virtualenv.py')}, 13:05:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:05:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:05:15 INFO - 'installer_path': 'installer.exe', 13:05:15 INFO - 'log_level': 'info', 13:05:15 INFO - 'log_name': 'talos', 13:05:15 INFO - 'log_to_console': True, 13:05:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:05:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:05:15 INFO - 'opt_config_files': (), 13:05:15 INFO - 'pip_index': False, 13:05:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:05:15 INFO - 'cmd': ('bash', 13:05:15 INFO - '-c', 13:05:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:05:15 INFO - 'enabled': True, 13:05:15 INFO - 'halt_on_failure': False, 13:05:15 INFO - 'name': 'check_screen_resolution'},), 13:05:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:05:15 INFO - 'cmd': ('bash', 13:05:15 INFO - '-c', 13:05:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:05:15 INFO - 'enabled': True, 13:05:15 INFO - 'halt_on_failure': False, 13:05:15 INFO - 'name': 'check_screen_resolution'},), 13:05:15 INFO - 'run_cmd_checks_enabled': True, 13:05:15 INFO - 'sps_profile': False, 13:05:15 INFO - 'sps_profile_interval': 0, 13:05:15 INFO - 'suite': 'dromaeojs-e10s', 13:05:15 INFO - 'system_bits': '32', 13:05:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:05:15 INFO - 'title': 't-yosemite-r7-0360', 13:05:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:05:15 INFO - 'use_talos_json': True, 13:05:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:05:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:05:15 INFO - 'work_dir': 'build'} 13:05:15 INFO - ##### 13:05:15 INFO - ##### Running clobber step. 13:05:15 INFO - ##### 13:05:15 INFO - Running pre-action listener: _resource_record_pre_action 13:05:15 INFO - Running main action method: clobber 13:05:15 INFO - rmtree: /builds/slave/test/build 13:05:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:05:16 INFO - Running post-action listener: _resource_record_post_action 13:05:16 INFO - ##### 13:05:16 INFO - ##### Finished clobber step (success) 13:05:16 INFO - ##### 13:05:16 INFO - ##### 13:05:16 INFO - ##### Running read-buildbot-config step. 13:05:16 INFO - ##### 13:05:16 INFO - Running pre-action listener: _resource_record_pre_action 13:05:16 INFO - Running main action method: read_buildbot_config 13:05:16 INFO - Using buildbot properties: 13:05:16 INFO - { 13:05:16 INFO - "project": "", 13:05:16 INFO - "product": "firefox", 13:05:16 INFO - "script_repo_revision": "production", 13:05:16 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 13:05:16 INFO - "repository": "", 13:05:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 13:05:16 INFO - "buildid": "20160520105741", 13:05:16 INFO - "pgo_build": "False", 13:05:16 INFO - "basedir": "/builds/slave/test", 13:05:16 INFO - "buildnumber": 0, 13:05:16 INFO - "slavename": "t-yosemite-r7-0360", 13:05:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:05:16 INFO - "platform": "yosemite_r7", 13:05:16 INFO - "scheduler": "tests-larch-macosx64-talos", 13:05:16 INFO - "branch": "larch", 13:05:16 INFO - "repo_path": "projects/larch", 13:05:16 INFO - "revision": "b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19", 13:05:16 INFO - "stage_platform": "macosx64", 13:05:16 INFO - "builduid": "77153f17d4a54554ba738427aa2c8d92", 13:05:16 INFO - "slavebuilddir": "test" 13:05:16 INFO - } 13:05:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:05:16 INFO - Running post-action listener: _resource_record_post_action 13:05:16 INFO - ##### 13:05:16 INFO - ##### Finished read-buildbot-config step (success) 13:05:16 INFO - ##### 13:05:16 INFO - ##### 13:05:16 INFO - ##### Running download-and-extract step. 13:05:16 INFO - ##### 13:05:16 INFO - Running pre-action listener: _resource_record_pre_action 13:05:16 INFO - Running main action method: download_and_extract 13:05:16 INFO - mkdir: /builds/slave/test/build/tests 13:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:16 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:05:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:05:21 INFO - Downloaded 1199 bytes. 13:05:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:21 INFO - Using the following test package requirements: 13:05:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:05:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:05:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:05:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'jsshell-mac.zip'], 13:05:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:05:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:05:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:05:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:05:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:05:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:05:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:21 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:05:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:05:24 INFO - Downloaded 18150533 bytes. 13:05:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:05:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:05:27 INFO - Return code: 0 13:05:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:05: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:27 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:05:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:05:31 INFO - Downloaded 18150533 bytes. 13:05:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:05:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:05:35 INFO - Return code: 0 13:05: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:35 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:05:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:05:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:05:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:05:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:05:36 INFO - Downloaded 10840160 bytes. 13:05:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:05:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:05:36 INFO - Return code: 0 13:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:36 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:05:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:05:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:05:40 INFO - Downloaded 110060727 bytes. 13:05:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:40 INFO - mkdir: /builds/slave/test/properties 13:05:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:05:40 INFO - Writing to file /builds/slave/test/properties/build_url 13:05:40 INFO - Contents: 13:05:40 INFO - build_url:https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:41 INFO - Running post-action listener: _resource_record_post_action 13:05:41 INFO - Running post-action listener: set_extra_try_arguments 13:05:41 INFO - ##### 13:05:41 INFO - ##### Finished download-and-extract step (success) 13:05:41 INFO - ##### 13:05:41 INFO - ##### 13:05:41 INFO - ##### Running populate-webroot step. 13:05:41 INFO - ##### 13:05:41 INFO - Running pre-action listener: _resource_record_pre_action 13:05:41 INFO - Running main action method: populate_webroot 13:05:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:05:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:05:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:05:41 INFO - '--tppagecycles', 13:05:41 INFO - '7'], 13:05:41 INFO - 'tests': ['tsvgm']}}, 13:05:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:05:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:05:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:05:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:05:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['damp', 'tps']}, 13:05:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'tests': ['damp', 'tps']}, 13:05:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['dromaeo_dom']}, 13:05:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:05:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'other-e10s': {'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['a11yr', 13:05:41 INFO - 'ts_paint', 13:05:41 INFO - 'tpaint', 13:05:41 INFO - 'sessionrestore', 13:05:41 INFO - 'sessionrestore_no_auto_restore', 13:05:41 INFO - 'tabpaint']}, 13:05:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['tsvgx', 13:05:41 INFO - 'tsvgr_opacity', 13:05:41 INFO - 'tart', 13:05:41 INFO - 'tscrollx', 13:05:41 INFO - 'cart']}, 13:05:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:05:41 INFO - 'tsvgr_opacity', 13:05:41 INFO - 'tart', 13:05:41 INFO - 'tscrollx', 13:05:41 INFO - 'cart']}, 13:05:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'talos_options': ['--disable-e10s'], 13:05:41 INFO - 'tests': ['tp5o']}, 13:05:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'tests': ['tp5o']}, 13:05:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'talos_options': ['--disable-e10s', 13:05:41 INFO - '--xperf_path', 13:05:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:41 INFO - 'tests': ['tp5n']}, 13:05:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:41 INFO - 'talos_options': ['--xperf_path', 13:05:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:41 INFO - 'tests': ['tp5n']}}, 13:05:41 INFO - 'talos.zip': {'path': '', 13:05:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:05:41 INFO - Running post-action listener: _resource_record_post_action 13:05:41 INFO - ##### 13:05:41 INFO - ##### Finished populate-webroot step (success) 13:05:41 INFO - ##### 13:05:41 INFO - ##### 13:05:41 INFO - ##### Running create-virtualenv step. 13:05:41 INFO - ##### 13:05:41 INFO - Running pre-action listener: _resource_record_pre_action 13:05:41 INFO - Running main action method: create_virtualenv 13:05:41 INFO - Creating virtualenv /builds/slave/test/build/venv 13:05:41 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 13:05:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:05:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:05:41 INFO - Using real prefix '/tools/python27' 13:05:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:05:42 INFO - Installing distribute.............................................................................................................................................................................................done. 13:05:45 INFO - Installing pip.................done. 13:05:45 INFO - Return code: 0 13:05:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:45 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 13:05:45 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 13:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:45 INFO - 'HOME': '/Users/cltbld', 13:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:45 INFO - 'LOGNAME': 'cltbld', 13:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:45 INFO - 'MOZ_NO_REMOTE': '1', 13:05:45 INFO - 'NO_EM_RESTART': '1', 13:05:45 INFO - 'PAGER': '/bin/cat', 13:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:45 INFO - 'PWD': '/builds/slave/test', 13:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:45 INFO - 'SHELL': '/bin/bash', 13:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:05:45 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:45 INFO - 'USER': 'cltbld', 13:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:45 INFO - 'XPC_FLAGS': '0x0', 13:05:45 INFO - 'XPC_SERVICE_NAME': '0', 13:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:45 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 13:05:45 INFO - Cleaning up... 13:05:45 INFO - Return code: 0 13:05:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:45 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>=0.7.1'] in /builds/slave/test/build 13:05:45 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>=0.7.1 13:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:45 INFO - 'HOME': '/Users/cltbld', 13:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:45 INFO - 'LOGNAME': 'cltbld', 13:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:45 INFO - 'MOZ_NO_REMOTE': '1', 13:05:45 INFO - 'NO_EM_RESTART': '1', 13:05:45 INFO - 'PAGER': '/bin/cat', 13:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:45 INFO - 'PWD': '/builds/slave/test', 13:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:45 INFO - 'SHELL': '/bin/bash', 13:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:05:45 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:45 INFO - 'USER': 'cltbld', 13:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:45 INFO - 'XPC_FLAGS': '0x0', 13:05:45 INFO - 'XPC_SERVICE_NAME': '0', 13:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:45 INFO - Downloading/unpacking psutil>=0.7.1 13:05:45 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 13:05:45 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 13:05:45 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 13:05:45 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 13:05:45 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 13:05:45 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 13:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:05:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:48 INFO - Installing collected packages: psutil 13:05:48 INFO - Running setup.py install for psutil 13:05:48 INFO - building 'psutil._psutil_osx' extension 13:05:48 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 13:05:49 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 13:05:49 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 13:05:49 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 13:05:49 INFO - building 'psutil._psutil_posix' extension 13:05:49 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 13:05:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:05:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:05:49 INFO - ^ 13:05:49 INFO - 1 warning generated. 13:05:49 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 13:05:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:49 INFO - Successfully installed psutil 13:05:49 INFO - Cleaning up... 13:05:49 INFO - Return code: 0 13:05:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:49 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:49 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.0.0'] in /builds/slave/test/build 13:05:49 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.0.0 13:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:49 INFO - 'HOME': '/Users/cltbld', 13:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:49 INFO - 'LOGNAME': 'cltbld', 13:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:49 INFO - 'MOZ_NO_REMOTE': '1', 13:05:49 INFO - 'NO_EM_RESTART': '1', 13:05:49 INFO - 'PAGER': '/bin/cat', 13:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:49 INFO - 'PWD': '/builds/slave/test', 13:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:49 INFO - 'SHELL': '/bin/bash', 13:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:05:49 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:49 INFO - 'USER': 'cltbld', 13:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:49 INFO - 'XPC_FLAGS': '0x0', 13:05:49 INFO - 'XPC_SERVICE_NAME': '0', 13:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:05:49 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 13:05:49 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 13:05:49 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 13:05:49 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 13:05:49 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 13:05:49 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 13:05:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:05:52 INFO - Installing collected packages: mozsystemmonitor 13:05:52 INFO - Running setup.py install for mozsystemmonitor 13:05:52 INFO - Successfully installed mozsystemmonitor 13:05:52 INFO - Cleaning up... 13:05:52 INFO - Return code: 0 13:05:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:52 INFO - 'HOME': '/Users/cltbld', 13:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:52 INFO - 'LOGNAME': 'cltbld', 13:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:52 INFO - 'MOZ_NO_REMOTE': '1', 13:05:52 INFO - 'NO_EM_RESTART': '1', 13:05:52 INFO - 'PAGER': '/bin/cat', 13:05:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:52 INFO - 'PWD': '/builds/slave/test', 13:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:52 INFO - 'SHELL': '/bin/bash', 13:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:05:52 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:52 INFO - 'USER': 'cltbld', 13:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:52 INFO - 'XPC_FLAGS': '0x0', 13:05:52 INFO - 'XPC_SERVICE_NAME': '0', 13:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:52 INFO - Downloading/unpacking blobuploader==1.2.4 13:05:52 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 13:05:52 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 13:05:52 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 13:05:52 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 13:05:52 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 13:05:52 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 13:05:55 INFO - Downloading blobuploader-1.2.4.tar.gz 13:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:05:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:05:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:55 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 13:05:56 INFO - Downloading docopt-0.6.1.tar.gz 13:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:05:56 INFO - Installing collected packages: blobuploader, requests, docopt 13:05:56 INFO - Running setup.py install for blobuploader 13:05:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:05:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:05:56 INFO - Running setup.py install for requests 13:05:57 INFO - Running setup.py install for docopt 13:05:57 INFO - Successfully installed blobuploader requests docopt 13:05:57 INFO - Cleaning up... 13:05:57 INFO - Return code: 0 13:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:57 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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:05:57 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 13:05:57 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 13:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:57 INFO - 'HOME': '/Users/cltbld', 13:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:57 INFO - 'LOGNAME': 'cltbld', 13:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:57 INFO - 'MOZ_NO_REMOTE': '1', 13:05:57 INFO - 'NO_EM_RESTART': '1', 13:05:57 INFO - 'PAGER': '/bin/cat', 13:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:57 INFO - 'PWD': '/builds/slave/test', 13:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:57 INFO - 'SHELL': '/bin/bash', 13:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:05:57 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:57 INFO - 'USER': 'cltbld', 13:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:57 INFO - 'XPC_FLAGS': '0x0', 13:05:57 INFO - 'XPC_SERVICE_NAME': '0', 13:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:05:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-KYz2lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:05:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-uxtuBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:05:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-vrvgph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:05:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Hyy1pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-6mIUKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-cyaqb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-T5Cuah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-T9UGUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-LqD1NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-toFT42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-YRfq_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-KFsQkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-dePbZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:05:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-pDFF70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:59 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-gAoEx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:05:59 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-GzJwRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:05:59 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-rTU8Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:05:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:05:59 INFO - Running setup.py install for manifestparser 13:05:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:05:59 INFO - Running setup.py install for mozcrash 13:05:59 INFO - Running setup.py install for mozdebug 13:05:59 INFO - Running setup.py install for mozdevice 13:05:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:05:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:05:59 INFO - Running setup.py install for mozfile 13:06:00 INFO - Running setup.py install for mozhttpd 13:06:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Running setup.py install for mozinfo 13:06:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Running setup.py install for mozInstall 13:06:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Running setup.py install for mozleak 13:06:00 INFO - Running setup.py install for mozlog 13:06:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Running setup.py install for moznetwork 13:06:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:06:00 INFO - Running setup.py install for mozprocess 13:06:01 INFO - Running setup.py install for mozprofile 13:06:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:06:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:06:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:06:01 INFO - Running setup.py install for mozrunner 13:06:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:06:01 INFO - Running setup.py install for mozscreenshot 13:06:01 INFO - Running setup.py install for moztest 13:06:01 INFO - Running setup.py install for mozversion 13:06:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:06:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:06:01 INFO - Cleaning up... 13:06:01 INFO - Return code: 0 13:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:01 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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:06:01 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 13:06:01 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 13:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:01 INFO - 'HOME': '/Users/cltbld', 13:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:01 INFO - 'LOGNAME': 'cltbld', 13:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:01 INFO - 'MOZ_NO_REMOTE': '1', 13:06:01 INFO - 'NO_EM_RESTART': '1', 13:06:01 INFO - 'PAGER': '/bin/cat', 13:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:01 INFO - 'PWD': '/builds/slave/test', 13:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:01 INFO - 'SHELL': '/bin/bash', 13:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:06:01 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:01 INFO - 'USER': 'cltbld', 13:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:01 INFO - 'XPC_FLAGS': '0x0', 13:06:01 INFO - 'XPC_SERVICE_NAME': '0', 13:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Xow5TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-M4Gh1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-sg2R76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-c9G5_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-hnm0za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-rIoUt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5nIall-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:02 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)) 13:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-36tGNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-73c6cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-fcLO8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-kCjo6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-PDFNw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-qUampM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-dak_8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5ZyjpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-CT3llo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:03 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)) 13:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:03 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-SuTcRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:06 INFO - Downloading blessings-1.6.tar.gz 13:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:06 INFO - Installing collected packages: blessings 13:06:06 INFO - Running setup.py install for blessings 13:06:06 INFO - Successfully installed blessings 13:06:06 INFO - Cleaning up... 13:06:06 INFO - Return code: 0 13:06:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:07 INFO - Reading from file tmpfile_stdout 13:06:07 INFO - Current package versions: 13:06:07 INFO - blessings == 1.6 13:06:07 INFO - blobuploader == 1.2.4 13:06:07 INFO - docopt == 0.6.1 13:06:07 INFO - manifestparser == 1.1 13:06:07 INFO - mozInstall == 1.12 13:06:07 INFO - mozcrash == 0.17 13:06:07 INFO - mozdebug == 0.1 13:06:07 INFO - mozdevice == 0.48 13:06:07 INFO - mozfile == 1.2 13:06:07 INFO - mozhttpd == 0.7 13:06:07 INFO - mozinfo == 0.9 13:06:07 INFO - mozleak == 0.1 13:06:07 INFO - mozlog == 3.1 13:06:07 INFO - moznetwork == 0.27 13:06:07 INFO - mozprocess == 0.22 13:06:07 INFO - mozprofile == 0.28 13:06:07 INFO - mozrunner == 6.11 13:06:07 INFO - mozscreenshot == 0.1 13:06:07 INFO - mozsystemmonitor == 0.0 13:06:07 INFO - moztest == 0.7 13:06:07 INFO - mozversion == 1.4 13:06:07 INFO - psutil == 3.1.1 13:06:07 INFO - requests == 1.2.3 13:06:07 INFO - wsgiref == 0.1.2 13:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:06:07 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 13:06:07 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 13:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:07 INFO - 'HOME': '/Users/cltbld', 13:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:07 INFO - 'LOGNAME': 'cltbld', 13:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:07 INFO - 'MOZ_NO_REMOTE': '1', 13:06:07 INFO - 'NO_EM_RESTART': '1', 13:06:07 INFO - 'PAGER': '/bin/cat', 13:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:07 INFO - 'PWD': '/builds/slave/test', 13:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:07 INFO - 'SHELL': '/bin/bash', 13:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:06:07 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:07 INFO - 'USER': 'cltbld', 13:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:07 INFO - 'XPC_FLAGS': '0x0', 13:06:07 INFO - 'XPC_SERVICE_NAME': '0', 13:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 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)) 13:06:07 INFO - Cleaning up... 13:06:07 INFO - Return code: 0 13:06:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:06:07 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 13:06:07 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 13:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:07 INFO - 'HOME': '/Users/cltbld', 13:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:07 INFO - 'LOGNAME': 'cltbld', 13:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:07 INFO - 'MOZ_NO_REMOTE': '1', 13:06:07 INFO - 'NO_EM_RESTART': '1', 13:06:07 INFO - 'PAGER': '/bin/cat', 13:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:07 INFO - 'PWD': '/builds/slave/test', 13:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:07 INFO - 'SHELL': '/bin/bash', 13:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:06:07 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:07 INFO - 'USER': 'cltbld', 13:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:07 INFO - 'XPC_FLAGS': '0x0', 13:06:07 INFO - 'XPC_SERVICE_NAME': '0', 13:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:07 INFO - Downloading/unpacking jsonschema 13:06:07 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 13:06:07 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 13:06:07 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 13:06:07 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 13:06:07 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 13:06:07 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 13:06:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:09 INFO - Downloading/unpacking functools32 (from jsonschema) 13:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:10 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:10 INFO - warning: no files found matching '*.txt' 13:06:10 INFO - no previously-included directories found matching 'build' 13:06:10 INFO - no previously-included directories found matching 'dist' 13:06:10 INFO - no previously-included directories found matching '.git*' 13:06:10 INFO - Installing collected packages: jsonschema, functools32 13:06:10 INFO - Running setup.py install for functools32 13:06:10 INFO - warning: no files found matching '*.txt' 13:06:10 INFO - no previously-included directories found matching 'build' 13:06:10 INFO - no previously-included directories found matching 'dist' 13:06:10 INFO - no previously-included directories found matching '.git*' 13:06:10 INFO - Successfully installed jsonschema functools32 13:06:10 INFO - Cleaning up... 13:06:10 INFO - Return code: 0 13:06:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:10 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e04a9ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, '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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 13:06:10 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', 'flake8'] in /builds/slave/test/build 13:06:10 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 flake8 13:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:10 INFO - 'HOME': '/Users/cltbld', 13:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:10 INFO - 'LOGNAME': 'cltbld', 13:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:10 INFO - 'MOZ_NO_REMOTE': '1', 13:06:10 INFO - 'NO_EM_RESTART': '1', 13:06:10 INFO - 'PAGER': '/bin/cat', 13:06:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:10 INFO - 'PWD': '/builds/slave/test', 13:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:10 INFO - 'SHELL': '/bin/bash', 13:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:06:10 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:10 INFO - 'USER': 'cltbld', 13:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:10 INFO - 'XPC_FLAGS': '0x0', 13:06:10 INFO - 'XPC_SERVICE_NAME': '0', 13:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:11 INFO - Downloading/unpacking flake8 13:06:11 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 13:06:11 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 13:06:11 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 13:06:11 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 13:06:11 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 13:06:11 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 13:06:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:06:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:06:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:13 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 13:06:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:06:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:06:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:06:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:06:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:06:14 INFO - no previously-included directories found matching 'docs/_build' 13:06:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:06:14 INFO - Running setup.py install for pep8 13:06:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:06:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:06:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:06:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:06:14 INFO - no previously-included directories found matching 'docs/_build' 13:06:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:06:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:06:15 INFO - Cleaning up... 13:06:15 INFO - Return code: 0 13:06:15 INFO - Running post-action listener: _resource_record_post_action 13:06:15 INFO - Running post-action listener: _start_resource_monitoring 13:06:15 INFO - Starting resource monitoring. 13:06:15 INFO - ##### 13:06:15 INFO - ##### Finished create-virtualenv step (success) 13:06:15 INFO - ##### 13:06:15 INFO - ##### 13:06:15 INFO - ##### Running install step. 13:06:15 INFO - ##### 13:06:15 INFO - Running pre-action listener: _resource_record_pre_action 13:06:15 INFO - Running main action method: install 13:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:15 INFO - Reading from file tmpfile_stdout 13:06:15 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:15 INFO - Reading from file tmpfile_stdout 13:06:15 INFO - Output received: 13:06:15 INFO - Usage: mozinstall [options] installer 13:06:15 INFO - Options: 13:06:15 INFO - -h, --help show this help message and exit 13:06:15 INFO - -d DEST, --destination=DEST 13:06:15 INFO - Directory to install application into. [default: 13:06:15 INFO - "/builds/slave/test"] 13:06:15 INFO - --app=APP Application being installed. [default: firefox] 13:06:15 INFO - mkdir: /builds/slave/test/build/application 13:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:06:48 INFO - Reading from file tmpfile_stdout 13:06:48 INFO - Output received: 13:06:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:06:48 INFO - Running post-action listener: _resource_record_post_action 13:06:48 INFO - ##### 13:06:48 INFO - ##### Finished install step (success) 13:06:48 INFO - ##### 13:06:48 INFO - ##### 13:06:48 INFO - ##### Running run-tests step. 13:06:48 INFO - ##### 13:06:48 INFO - Running pre-action listener: _resource_record_pre_action 13:06:48 INFO - Running main action method: run_tests 13:06:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:06:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:06:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:06:48 INFO - 2016-05-20 13:06:48.564 screenresolution[1930:26132] starting screenresolution argv=screenresolution get 13:06:48 INFO - 2016-05-20 13:06:48.566 screenresolution[1930:26132] Display 0: 1600x1200x32@60 13:06:48 INFO - 2016-05-20 13:06:48.572 screenresolution[1931:26134] starting screenresolution argv=screenresolution list 13:06:48 INFO - Available Modes on Display 0 13:06:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:06:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:06:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:06:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:06:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:06:48 INFO - Intel Iris: 13:06:48 INFO - Chipset Model: Intel Iris 13:06:48 INFO - Type: GPU 13:06:48 INFO - Bus: Built-In 13:06:48 INFO - VRAM (Dynamic, Max): 1536 MB 13:06:48 INFO - Vendor: Intel (0x8086) 13:06:48 INFO - Device ID: 0x0a2e 13:06:48 INFO - Revision ID: 0x0009 13:06:48 INFO - Displays: 13:06:48 INFO - Display: 13:06:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:06:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:06:48 INFO - Display Serial Number: 1600x1200 60 13:06:48 INFO - Main Display: Yes 13:06:48 INFO - Mirror: Off 13:06:48 INFO - Online: Yes 13:06:48 INFO - Rotation: Supported 13:06:48 INFO - Return code: 0 13:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:06:48 INFO - Python 2.7.3 13:06:48 INFO - Return code: 0 13:06:48 INFO - grabbing minidump binary from tooltool 13:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:48 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 0x100b74d30>, '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 0x100998db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b6bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:06:48 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 13:06:48 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 13:06:48 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 13:06:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:06:48 INFO - Return code: 0 13:06:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:06:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:06:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:06:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:06:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:06:50 INFO - Return code: 0 13:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 13:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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 13:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nao23kY6i8/Render', 13:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:50 INFO - 'HOME': '/Users/cltbld', 13:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:50 INFO - 'LOGNAME': 'cltbld', 13:06:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:06:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:50 INFO - 'MOZ_NO_REMOTE': '1', 13:06:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:06:50 INFO - 'NO_EM_RESTART': '1', 13:06:50 INFO - 'PAGER': '/bin/cat', 13:06:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:50 INFO - 'PWD': '/builds/slave/test', 13:06:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:50 INFO - 'SHELL': '/bin/bash', 13:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PqgB8Cyp4g/Listeners', 13:06:50 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 13:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:50 INFO - 'USER': 'cltbld', 13:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:50 INFO - 'XPC_FLAGS': '0x0', 13:06:50 INFO - 'XPC_SERVICE_NAME': '0', 13:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 13:06:50 INFO - mozversion application_buildid: 20160520105741 13:06:50 INFO - mozversion application_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 13:06:50 INFO - mozversion application_display_name: Nightly 13:06:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:06:50 INFO - mozversion application_name: Firefox 13:06:50 INFO - mozversion application_remotingname: firefox 13:06:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:06:50 INFO - mozversion application_vendor: Mozilla 13:06:50 INFO - mozversion application_version: 49.0a1 13:06:50 INFO - mozversion platform_buildid: 20160520105741 13:06:50 INFO - mozversion platform_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 13:06:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:06:50 INFO - mozversion platform_version: 49.0a1 13:06:50 INFO - using testdate: 1463774810 13:06:50 INFO - actual date: 1463774810 13:06:50 INFO - starting webserver on 'localhost:52752' 13:06:50 INFO - SUITE-START | Running 2 tests 13:06:50 INFO - TEST-START | dromaeo_css 13:06:50 INFO - operating with platform_type : mac_ 13:06:50 INFO - Initialising browser for dromaeo_css test... 13:06:50 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmp37MZlx/profile http://localhost:52752/getInfo.html) 13:06:53 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 13:06:53 INFO - PROCESS | 1949 | colorDepth:24 13:06:53 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 13:06:53 INFO - PROCESS | 1949 | __metrics 13:06:53 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:06:54 INFO - TEST-INFO | 1949: exit 0 13:06:54 INFO - Browser initialized. 13:06:54 INFO - Running cycle 1/1 for dromaeo_css test... 13:06:54 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmp37MZlx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:06 INFO - PROCESS | 1952 | RSS: Main: 249982976 13:07:06 INFO - PROCESS | 1952 | 13:07:55 INFO - PROCESS | 1952 | 1463774875640 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] 13:10:06 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52752/tests/dromaeo/cssquery-ext.html) 13:10:07 INFO - PROCESS | 1952 | RSS: Main: 263114752 13:10:07 INFO - PROCESS | 1952 | 13:13:09 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-ext.html (next: http://localhost:52752/tests/dromaeo/cssquery-jquery.html) 13:13:10 INFO - PROCESS | 1952 | RSS: Main: 275017728 13:13:10 INFO - PROCESS | 1952 | 13:16:12 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52752/tests/dromaeo/cssquery-mootools.html) 13:16:13 INFO - PROCESS | 1952 | RSS: Main: 277094400 13:16:13 INFO - PROCESS | 1952 | 13:19:15 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52752/tests/dromaeo/cssquery-prototype.html) 13:19:15 INFO - PROCESS | 1952 | RSS: Main: 278642688 13:19:15 INFO - PROCESS | 1952 | 13:22:18 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52752/tests/dromaeo/cssquery-yui.html) 13:22:19 INFO - PROCESS | 1952 | RSS: Main: 281563136 13:22:19 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52752/tests/dromaeo/cssquery-yui.html (next: http://localhost:52752/tests/dromaeo/cssquery-dojo.html) 13:25:21 INFO - PROCESS | 1952 | RSS: Main: 282701824 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | __start_tp_report 13:25:21 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 13:25:21 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 13:25:21 INFO - PROCESS | 1952 | |i|pagename|runs| 13:25:21 INFO - PROCESS | 1952 | |0;dojo.html;3463.2554000889804;3492.8777492787754;3518.1204698825295;3525.6121826599056;3538.8230588470587;4676.275177347512;5654.74553645086;5705.828825135248;5745.138229265613;5802.158686990384;5719.227904232927;5726.885462290752;5755.079187330028;5765.855853603652;5856.033754430514;4397.274449715793;5129.9230511542355;5218.6868787872845;5239;5303.124984377573;4881.438634557018;5302.125149350371;5324.7071411072375;5371.865703357428;5388.164834450647;4331.328644060176;5141.177411614142;5151.150060240056;5264;5285.973570132163;5232.2674825524455;5243.711595862226;5248.763805628756;5275.103232450492;5314.840554783363;5052.040112378636;5192.71440070796;5202.193659982709;5203.791848326063;5217.5565076968505;5628;5658.603897727161;5669.744861481243;5754.4533269339345;5759.452851979097;5586.24585680932;5892.734826932799;6241.251049873998;6241.28225254097;6321.87356252877;6188.47397971173;6191.226096737908;6215.378462153749;6280.623162610258;6287.559870809046;5867.530597552186;5917.171595976567;5929.407059294079;5936.28764548257;5961.970190149065;6277.435030847246;6287.9056814147825;6297.2128483939505;6303.180586523723;6470.22357317125;6110.7861224856915;6143.051335359052;6150.969245153746;6206.782762603287;6207.81376558704;6144.709610981744;6234.937650623436;6262.780802671885;6276.748930042747;6278.46633036197;5375.247465354853;6016.278046634432;6150.231221097363;6271.62370257786;6276.9372306276355;6144.77104579086;6165.630062196283;6169.2288463942;6170.012797952396;6283;4989.201727723619;5016.44819069902;5019.774110165052;5029.0947629427055;5033.19468884984;4437.5784300491405;4492.752898590544;4496.145732310916;4496.460424749051;4533.750643714561;5634.464725851038;5678.488935995781;5686.118651609007;5781.739821708034;5784.363719990798;6254.3745625437095;6260.874782504416;6268.4671802896355;6269.279032911274;6299.590526615755;5614.2701448811395;5774.711264436762;5783.971080144573;5809.24479817621;5809.970950145223;5420.620556561082;5442.700651464128;5485.314335708036;5519;5520.751566179531;6465.191851018622;6475.514336424787;6477.352264773579;6496.577722448026;6502.934970650422;6198.194234749454;6204.5036397089025;6246.968765156145;6286.214223222098;6293.37066293367;6280.874382512416;6388.329225431263;6445.387688169804;6462.159919210472;6470.547061705824;6247.9062814056915;6272.529560283088;6291.150694656163;6295.433410993032;6301.243850737941;6171.043488259327;6215.720292586754;6240.968795155995;6251.749930002749;6282.403171698681;4963.8262660806695;6120.632762034292;6130.049842274455;6171.59884607499;6260.812175634738;5196.870078247923;6216.409441103088;6218;6225.501959843111;6229.408206220402;5041.621878359211;5900.203472531153;6043.607165534227;6075.696215189311;6131.110988906673;6104.883608894006;6105.277797093486;6143.385661433821;6192.195014648067;6206.751729930752;6082.939170608237;6092.299385570539;6096.493844838371;6104.786332478342;6139.29398119222 13:25:21 INFO - PROCESS | 1952 | |1;ext.html;498.9101961646902;515.2991930973876;558.4276116980095;560;570.4808624152023;37077.62922370776;37803.62196378035;39711.80144099283;39733.60266397335;39846.00384990377;40574.18851622973;41155;41241;41330.58669413305;41382.58617413825;8208.220219079178;9433.95283023584;9677.90322096777;9685.370450920704;9703.07820757027;9482.146606805387;9493.860736711604;9590.088941550577;9615.711528654152;9650.855237171412;9564.617415303379;9623.518824058805;9639.180669643054;9640.75898102546;9645.373050751714;9541.141297283279;9548.331616786827;9572.952135239315;9620.134187923086;9630.133288004043;9608.327417080807;9731.90268097324;9732.513374331254;9738;9762.853557196648;31697.683023169935;38395.23209535822;38513.61486385128;38828;38876.416853747214;2793.622860913771;3003.849807509616;3105.5186446100893;3160.2415420299194;3184.7452203823636;27135.379195713154;27185.048523301786;27299.044533441433;27512.862435687694;28026;2661.680598328194;2663.5605125154325;2677.54481738105;2715.551933930919;2748.6426764520684;413896;414589;417013.8298616975;419020;419659;34574.13564660904;35532;35628;35641.64358356435;35753.39109740068;36774.8161259192;36983.445248321295;37265.44101838474;37923.62076379256;38220.61779382171;14815;16566.254518546695;16791.748123778154;16958.660826783394;17008.744868826976;12729.363531823372;12884.549040783528;12896.548620798227;12911.22532648044;12940.417681204368;17036;19495.610087798166;20364.083616237305;20475.4881127973;20673.276435324693;7121.323474269936;7179.964100179465;7472.692278851179;7646.617669116522;7881.5271083734015;743.7582785594703;941.7928055827707;966.3911735606663;1066.2536224642781;1092.907102896247;29569;30008.849955750517;30017.849910750745;30445.543316850264;30703.692963070083;943.0522325063405;1108.3737688206138;1162.3723189477587;1180.2033627301537;1208.1542919956066;12915.739745544492;13116.737665246641;14624.780628290584;15272.465463708717;15422.46021389246;540448;684593;685482.5725871339;685731;690660;48612;53929.38211853651;58394.41605583975;59025.70487147623;60591.09113363303;1251.217988757027;1456.4611093895326;1462.4808193091512;1494.5516345096428;1504.8344682084962;29496.410071798873;29737;29969.850150749106;30478.69521304847;30510;53071.20393194028;53226.73386633042;53279;53438.66403339947;53665;581996;587177;595369;595647;600508;26257.474850503266;26407.019473539178;27296.590551142144;27517.587236191073;27675.72324276812;39153.608463915;44497.33254001128;45410;45593.77203113963;46902.06195876132;37022.889313320644;38051.80974095112;38508.614913850506;39014;39398;34142.487862681584;36427;37867.053323667125;38152.42771358376;38953.80523097367;37527.061823454635;37838.810805945795;37857.432138518496;38619;39412.802935985135;32541.67458325481;33850.83074584611;33938.012536234266;34302.48546271758;34368.82815585906;23987.52024959456;25193.37016574527;25216.243512694648;25422.110226141995;25631.487370252864 13:25:21 INFO - PROCESS | 1952 | |2;jquery.html;356.1772305973202;380.54904937648877;391.9686425085993;395.3318891074085;407.977561234132;17838.732419013726;20287.49281267969;20580.48548786281;22600.095996160155;22643.207487737913;12054.216475929059;12496.625101246955;12499.625011249655;12757;21208.4697882553;4953;5471.644343117695;5648.717564121798;5665.518430933355;5672.886542269152;3921.274564205617;4856.708597484162;6173.197484327031;6193.535484838632;6228.657423841687;5657.688827114507;6225.2529696436495;6225.62646241227;6238.750449982018;6244.656543890084;5897.439743224387;6024.638521688691;6024.819255422344;6026.156338112668;6041.365656606037;5720.742566584514;5774.364819869811;5850.619709718854;6071.817845464643;6078.45293923549;13769.931150344284;13941.512047078304;14014.089084209494;14138.868890488737;14251.287435628281;2189.8576592521435;2355.658429527709;2604.4400453902313;2752.6696796384363;2819.7603203727504;9519.952400238024;10215.94892025535;10303.793924121475;10404.583816647326;10488.632897848538;1777.7777777777778;1823.8085001074828;1826.6985947318676;1832.5418645338666;1835.3025850176848;83455;85528.57235713844;116475.8352416482;137796.9330460034;140396;9499.667511637059;11498.54005839757;11735.823962640567;11948.940255298841;12017.63947081589;12887.806682899763;15434.536963891102;15455;15480.922595387176;15498.845011549965;6746.831329216809;7749.535027898344;8697.565121743888;8758.781030474289;8836.602352894006;8576.485410875368;8592.828143437222;8599.269062129664;8661.566921654017;8708.43395179324;4651.86044418668;4763.261694437368;4764.2615394613895;4770.785314660849;4866.343043689128;1827.1229809691156;1913.3685883658359;2040.9897950510153;2047.2015913793632;2093.8324934005245;852.7313896122826;922.257582645963;997.2149851765259;1013.6806905824642;1041.3491567770143;17071.743923841153;20185.49536261576;20268;20690.793092069187;21153.68269475959;895.1272509303377;910.8588168833842;927.5779520318239;939.4504215034145;949.3971328206638;10411.895881041244;10783.352998820095;10869.29349592274;10882.673519794418;10899.509522071527;588735;591593;593005;606055;628971;111287;141382;141461;141558;144162;1012.5696578953915;1027.4041056187393;1037.2946396450636;1043.113353649392;1043.3114144664485;16327.918360408123;16537.586560336087;18227;18475.445736627924;18595.97722125242;41026.58973410228;49759;50407.747961259956;52077;52590.15934442276;60193;76818.61590692011;77408.61295693486;78336.82494762697;78676;8186;8276.958615207126;8288.33693304522;8421.157884211774;8533.359998000298;8407.663693452194;12464;12829.037822163189;12845.293508857103;12922.353882305659;7403.5928023959195;9697.369670971364;10869.913008699066;10974.176936729618;11301.943490282496;7658.578778167254;10661.840072399076;10723.30298530593;10724.249302548747;11285.266457680225;8149.225823546753;10607.469626518798;10830.945845270724;11763.647090587296;11842.111841612086;8332.583370831313;8378.664853405795;8441;8443.071262161035;8476.660933562589;8480.321574274167;10268.64059757905;10892.891071089187;11134.33194008362;11174.106071514097 13:25:21 INFO - PROCESS | 1952 | |3;mootools.html;1621.1894052973516;1657.5524608355745;1951.599922015987;2009.5880344529373;2016.233831144164;8221.177882211776;8829.558522073903;8852.690155844546;9378.99999999999;9384.953075234624;7927.088384835745;8856.715776212446;8907.866382004275;8926.553672316391;9091.681791137311;2419.600765873633;2442.2795275393714;2444.4622183119686;2448.5837407640656;2452.766987136219;2059.6498595238772;2151.5481748832685;2164.2966036038274;2401.399650087478;2430.538197742432;2045.864545177426;2423.563758523465;2427.78149966503;2443.450223699671;2448.9755102449017;2200.3508964855405;2216.002798740565;2419.2742177346813;2429.465517586137;2430.2952143878256;2409.8192635552405;2422.7213870404953;2429.4898071405028;2433.124075332879;2438.7439318871616;8987.505687187202;8994.955025224897;9048.909510904938;9059.864102038475;9103.044180361034;5394.109971854639;5545.445455454463;5558.888822223574;5566.443355664442;5601.60788744788;3621.6740493355724;3625.148090198801;3640.0899775056237;3704.833282502294;3710.2023065040876;1010.540204207084;1043.7390652336917;1046.1892033673964;1047.9266451348408;1062.941538215398;12022.819657705142;12171.452284647123;12211.511539538407;12218.450169742382;12246.938765306117;4902.558769710672;4926.852194434173;5064.620153488503;5079.74601269935;5123.641345105807;10449.00734430228;10454.11140053104;10461.215408844368;10465.581376744996;10474.633387831389;5463.344398672185;5490.203920431595;5556.694176868433;5558.749856256478;5581.972090139605;4485.327200919888;4504.504504504502;4990.5508504234795;5001.824936127218;5091.821786237464;4143.958560414418;4144.291617591278;4155.688323375759;4186.644135248477;4251.489821221411;1329.9335033248299;1357.3688234970787;1364.36557000995;1367.2616786934943;1368.9247091409868;811.7483580090191;909.1771946388529;946.3517490519016;971.6696323250129;976.1409959235828;4785.976070119627;6053.51571874249;6091.0558863376245;6130.908036379458;6175.876482470326;450.7430764464224;454.5363728996405;460.5762698317556;477.4032459425718;478.07015355133933;3955.6044395560216;4006.1186538961383;4028.1540876415693;4077.1030373318417;4113.568075352105;8239.546824924566;10803.729906752395;11143;11184;11226.943865280622;10845.023947844575;10910.563577456971;10995.34027958325;11039.39283339424;11152.386618736047;7968.880466793003;8379.49723016621;8405.453645513022;8497.320214382835;8535.445196062235;5673.177389278449;5685.692290773062;6046.334903160737;6102.633841969496;6251.062340648939;10006.799864002534;10161.84757228657;10361.689149325231;10399.532021059222;10416.843747343948;10265.2301077418;10510.211734120125;10543.94728026355;10624.36253824773;10639.734006649896;7130.001799748143;7485.962570187115;7655.732049378245;7723.845523089394;7785.688572457038;8532.317414606692;8563.486190828571;8608.91391086081;8627;8634.61144248523;6742.224644165984;6996.090508233897;7478.326950574475;7539.170691224071;7828.960855195687;7415.740449084472;7480.214577469287;7510;7533.171351151478;7595.354394876497;6115.877682446415;6921.2386637470845;7002.1947476040905;7010.47421443404;7011.579305241702;4577.843672985701;4870.220764677634;5035.446100928822;5123;5231.790728370823;4394.582514661102;5054.16606259963;5134.255532947776;5240.764165612481;5315.946840531546 13:25:21 INFO - PROCESS | 1952 | |4;prototype.html;301.55370052322564;306.65654466996966;316.2631069607814;322.88214801597417;326.59502217250616;5151.613628977832;5207.0106679730825;5295.735213239342;5357.2232026356205;5439.4288599697;4310.612044915957;4462.991653755815;4542.6138778203895;4555.612772914306;5269.710165940881;4108.3221268490815;4142.917141657165;4154.854580089697;4157.52188498322;4160.334346504553;3741.86903458379;4080.5720596245337;4112.198121366319;4149.211649786546;4164.812583433738;3972.602739726033;4093.631573158415;4127.958720412803;4136.048708796979;4167.020750123718;3869.413540448431;4129.91740165198;4140.3168477201225;4155.522114956789;4172.645325147366;3227.8386080696105;3424.938269136576;3603.621619729943;3605.0987253186704;3638.8362523686496;4479.238529449969;5130.127878260705;5139.329717841716;5172.844814655567;5221.242919776649;4149.605787450188;4356.869293921156;4538.662588766788;4672.532746725335;4746.810127594892;5146.819861304836;5497.890042199134;5689.923178039986;5696.572757043238;5707.571932105108;3808.1720774028345;3875.65119139276;3957.6042395760483;3984.721069525134;3990.4213888986224;4640.651951103647;5200.401953775327;5292.603054770908;5591.496765291103;6056.242969628796;4035.1122752994293;4067.2678917794487;4078.5921407858978;4079.389190917815;4083.0813067060267;3312.585926759155;3416.24842534642;3470.704990075872;3882.9127844203667;4131.400946862688;4021.2962731521866;4144.570123307454;4341.08837144197;4699.412573428322;4776.785044672929;5274.947250527445;5358.2498450217;5760.897272495593;5860.150278209636;5880.147378630075;1117.8938000889902;1376.3256004557895;1402.2401885633465;1417.4259424932918;1417.9932248103962;799.696115476127;842.2672275120684;857.524074138859;872.7381785464336;873.7509809704204;4051.9870032491876;4179.2477354076555;4272.33778764292;4495.685302028826;4507.63938884895;4484.031632005634;4710;4793.544613261734;4840.394950631171;4954.851354459372;4009.0979529605606;4090.793216001287;4221.016122422457;4463.66522510813;4504.477492604152;2152.3096129837704;2209.9502736200498;2373.798227150677;2403.375122468171;2674.4784766970256;5202.479752024768;5234.502722241381;5253.452075743261;5261.631685781958;5344.156266088194;4317.76943571087;4582.152301824173;4605.0559635274685;4720.843393368578;4996.752111127796;4278.893027674333;4576;4903.362562866876;5078.289039534542;5263.868403289948;4269.679774016899;4280.838176720736;4425.446819147606;4576.610988065919;5035.4712755161645;4267.701260911831;4298.76356800379;4524.819007239674;4819.7108173509705;4870.707757534559;4298.892527686708;4338.58783415575;4783.976080119577;5065.784211789218;5218.947810521846;3000.73968933053;3548.4677298405445;3555.2711694103164;3574.57105147384;3593.9810559130456;4419.226635338867;4579.656525760648;4810.742492893744;4880.389951256093;5616.887662246814;5211.530962213419;5537.5569954404355;5647.774089036392;5776.682282474504;5827.125931110368;5457.263269458573;5700.828975130753;5740.933264024864;5757.280339957505;5764.587676019402;4674.836380726658;4680.7893644786745;5134.7946082156295;5178.948210517847;5237.790488380422;4760.809567617256;5134.358205224347;5270.947290527046;5282.418933917343;5757.395473475225;4922.956972856225;5008.899822003466;5560.499555040065;5623.803166889139;5625.887482250414 13:25:21 INFO - PROCESS | 1952 | |5;yui.html;631.8704665543563;651.244556314675;688.328879342641;705.7106586299618;738.68236658237;13501.729965400686;14807.741341985931;15161.090334579932;15382.384704611803;16264.75602865955;15457.30442130104;15827.97118187317;15832.44586439475;15838.76241856373;15848.920755396206;899.8425275576748;906.5104843384597;950.1211379473993;950.363256618064;961.4904100826539;917.7980844214262;922.4649703172144;932.1051790281338;936.5504557812254;937.9822892162013;933.0016881936331;934.0845970948475;980.9803803923917;994.6618149829056;1000.7548150703088;987.210231814549;987.8715766950287;994.1847684898386;1010.540204207084;1014.3761586624173;733.6405161470894;738.5199620246829;740.496462405564;741.6106544064355;749.4940914882487;14570.271486425743;15163.166025868573;15194.240287985667;15194.772078418833;15206.783457323387;12394.504219831197;12478.563250286197;12786.936065319614;12865.228086314757;13360.86639133606;10030.29882963342;10119.443430611313;10166.288359814816;10363.067323940806;10442.895571044344;3497.1257185703575;3551.183227857581;3583.910402239939;3597;3670.853165873362;85540.57229713812;85661.57169214114;85730;86503;86886.5655671724;7846.921530784619;9620.13418792298;9649.13157815786;9706.223502119814;9862.211023118276;22247.777522224893;22691.773082268966;22705.09179632796;22758.772412275997;22819.315420537412;7703.768886933401;7766.106897706723;7880.093789214199;7890.9605451972375;7901.288884000468;3605.260921511084;4079.653229475528;4156.085661154541;4166.604172603597;4185.853495127717;3015.3969206158854;3515.7011654009184;3657.213699054716;3665.9816700916326;3691.8338674759702;596.4244504053609;627.1533429869639;664.5647101148755;665.9334066593302;672.9259781424039;556.2157358125024;582.977647566214;586.1383765864173;611.8898598252268;617.8918689229367;7979.72070977513;8038.196180381915;8092.3930705197345;8284.337253019745;8340.874886876702;574.8218052403769;586.2876604925036;593.801076639322;602.3103546439303;617.200725061047;3789.6778773804535;3925.2542017017245;3926.842926282917;3929.057026313664;3958.5801456337445;89690;90152.09847901568;90991;91496.54251728699;91763.08236917679;80533.59733201297;80815.59592202002;80886.59556702296;81340;82253;72836;73366;73967;74171.62914185395;75022.62488687522;7649.808754781175;7657.34912532441;7702.807429814299;7763.650635721293;7778.455508114604;41833.372499411926;41998.58001419947;42577.50978715854;45142.09715805731;45837.31244031276;30249.697503024687;30359.93740219082;30369.696303036686;59259.96290315199;60040.39959600523;10887.074598658886;11011.174161937726;11032.227744057838;11273.041791447818;11548.133889958119;11804.527818887149;11898.405079746151;12042.096842736655;12202.32887191213;12388.070894682754;11258.268212566158;11313;11341;11349;11760.70598235051;11247.212695111264;11462.484188211718;11547.769044619228;11845.822312665488;11849.822252666045;11020.193082054813;11091.260979604118;11219.495122719334;11354.26197297173;11504.907033831772;9563.187129094105;9573.808523829344;9690.806183876142;9815.607375704893;9933.751656208653;8904.109589041043;9938.503074846374;9943.502824858584;9967.302288839712;9979.01771064314 13:25:21 INFO - PROCESS | 1952 | __end_tp_report 13:25:21 INFO - PROCESS | 1952 | __start_cc_report 13:25:21 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,415 13:25:21 INFO - PROCESS | 1952 | __end_cc_report 13:25:21 INFO - PROCESS | 1952 | __startTimestamp1463775921962__endTimestamp 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | ------- Summary: start ------- 13:25:21 INFO - PROCESS | 1952 | Number of tests: 6 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5752.51 Median:6016.28 stddev:655.94 (10.9%) stddev-sans-first:632.89 13:25:21 INFO - PROCESS | 1952 | Values: 3463.3 3492.9 3518.1 3525.6 3538.8 4676.3 5654.7 5705.8 5745.1 5802.2 5719.2 5726.9 5755.1 5765.9 5856.0 4397.3 5129.9 5218.7 5239.0 5303.1 4881.4 5302.1 5324.7 5371.9 5388.2 4331.3 5141.2 5151.2 5264.0 5286.0 5232.3 5243.7 5248.8 5275.1 5314.8 5052.0 5192.7 5202.2 5203.8 5217.6 5628.0 5658.6 5669.7 5754.5 5759.5 5586.2 5892.7 6241.3 6241.3 6321.9 6188.5 6191.2 6215.4 6280.6 6287.6 5867.5 5917.2 5929.4 5936.3 5962.0 6277.4 6287.9 6297.2 6303.2 6470.2 6110.8 6143.1 6151.0 6206.8 6207.8 6144.7 6234.9 6262.8 6276.7 6278.5 5375.2 6016.3 6150.2 6271.6 6276.9 6144.8 6165.6 6169.2 6170.0 6283.0 4989.2 5016.4 5019.8 5029.1 5033.2 4437.6 4492.8 4496.1 4496.5 4533.8 5634.5 5678.5 5686.1 5781.7 5784.4 6254.4 6260.9 6268.5 6269.3 6299.6 5614.3 5774.7 5784.0 5809.2 5810.0 5420.6 5442.7 5485.3 5519.0 5520.8 6465.2 6475.5 6477.4 6496.6 6502.9 6198.2 6204.5 6247.0 6286.2 6293.4 6280.9 6388.3 6445.4 6462.2 6470.5 6247.9 6272.5 6291.2 6295.4 6301.2 6171.0 6215.7 6241.0 6251.7 6282.4 4963.8 6120.6 6130.0 6171.6 6260.8 5196.9 6216.4 6218.0 6225.5 6229.4 5041.6 5900.2 6043.6 6075.7 6131.1 6104.9 6105.3 6143.4 6192.2 6206.8 6082.9 6092.3 6096.5 6104.8 6139.3 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:67382.68 Median:38102.12 stddev:151596.41 (397.9%) stddev-sans-first:151938.50 13:25:21 INFO - PROCESS | 1952 | Values: 498.9 515.3 558.4 560.0 570.5 37077.6 37803.6 39711.8 39733.6 39846.0 40574.2 41155.0 41241.0 41330.6 41382.6 8208.2 9434.0 9677.9 9685.4 9703.1 9482.1 9493.9 9590.1 9615.7 9650.9 9564.6 9623.5 9639.2 9640.8 9645.4 9541.1 9548.3 9573.0 9620.1 9630.1 9608.3 9731.9 9732.5 9738.0 9762.9 31697.7 38395.2 38513.6 38828.0 38876.4 2793.6 3003.8 3105.5 3160.2 3184.7 27135.4 27185.0 27299.0 27512.9 28026.0 2661.7 2663.6 2677.5 2715.6 2748.6 413896.0 414589.0 417013.8 419020.0 419659.0 34574.1 35532.0 35628.0 35641.6 35753.4 36774.8 36983.4 37265.4 37923.6 38220.6 14815.0 16566.3 16791.7 16958.7 17008.7 12729.4 12884.5 12896.5 12911.2 12940.4 17036.0 19495.6 20364.1 20475.5 20673.3 7121.3 7180.0 7472.7 7646.6 7881.5 743.8 941.8 966.4 1066.3 1092.9 29569.0 30008.8 30017.8 30445.5 30703.7 943.1 1108.4 1162.4 1180.2 1208.2 12915.7 13116.7 14624.8 15272.5 15422.5 540448.0 684593.0 685482.6 685731.0 690660.0 48612.0 53929.4 58394.4 59025.7 60591.1 1251.2 1456.5 1462.5 1494.6 1504.8 29496.4 29737.0 29969.9 30478.7 30510.0 53071.2 53226.7 53279.0 53438.7 53665.0 581996.0 587177.0 595369.0 595647.0 600508.0 26257.5 26407.0 27296.6 27517.6 27675.7 39153.6 44497.3 45410.0 45593.8 46902.1 37022.9 38051.8 38508.6 39014.0 39398.0 34142.5 36427.0 37867.1 38152.4 38953.8 37527.1 37838.8 37857.4 38619.0 39412.8 32541.7 33850.8 33938.0 34302.5 34368.8 23987.5 25193.4 25216.2 25422.1 25631.5 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:34381.20 Median:2786.22 stddev:100672.05 (3613.2%) stddev-sans-first:100922.05 13:25:21 INFO - PROCESS | 1952 | Values: 356.2 380.5 392.0 395.3 408.0 17838.7 20287.5 20580.5 22600.1 22643.2 12054.2 12496.6 12499.6 12757.0 21208.5 4953.0 5471.6 5648.7 5665.5 5672.9 3921.3 4856.7 6173.2 6193.5 6228.7 5657.7 6225.3 6225.6 6238.8 6244.7 5897.4 6024.6 6024.8 6026.2 6041.4 5720.7 5774.4 5850.6 6071.8 6078.5 13769.9 13941.5 14014.1 14138.9 14251.3 2189.9 2355.7 2604.4 2752.7 2819.8 9520.0 10215.9 10303.8 10404.6 10488.6 1777.8 1823.8 1826.7 1832.5 1835.3 83455.0 85528.6 116475.8 137796.9 140396.0 9499.7 11498.5 11735.8 11948.9 12017.6 12887.8 15434.5 15455.0 15480.9 15498.8 6746.8 7749.5 8697.6 8758.8 8836.6 8576.5 8592.8 8599.3 8661.6 8708.4 4651.9 4763.3 4764.3 4770.8 4866.3 1827.1 1913.4 2041.0 2047.2 2093.8 852.7 922.3 997.2 1013.7 1041.3 17071.7 20185.5 20268.0 20690.8 21153.7 895.1 910.9 927.6 939.5 949.4 10411.9 10783.4 10869.3 10882.7 10899.5 588735.0 591593.0 593005.0 606055.0 628971.0 111287.0 141382.0 141461.0 141558.0 144162.0 1012.6 1027.4 1037.3 1043.1 1043.3 16327.9 16537.6 18227.0 18475.4 18596.0 41026.6 49759.0 50407.7 52077.0 52590.2 60193.0 76818.6 77408.6 78336.8 78676.0 8186.0 8277.0 8288.3 8421.2 8533.4 8407.7 12464.0 12829.0 12845.3 12922.4 7403.6 9697.4 10869.9 10974.2 11301.9 7658.6 10661.8 10723.3 10724.2 11285.3 8149.2 10607.5 10830.9 11763.6 11842.1 8332.6 8378.7 8441.0 8443.1 8476.7 8480.3 10268.6 10892.9 11134.3 11174.1 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5827.70 Median:468.99 stddev:3308.63 (705.5%) stddev-sans-first:3302.81 13:25:21 INFO - PROCESS | 1952 | Values: 1621.2 1657.6 1951.6 2009.6 2016.2 8221.2 8829.6 8852.7 9379.0 9385.0 7927.1 8856.7 8907.9 8926.6 9091.7 2419.6 2442.3 2444.5 2448.6 2452.8 2059.6 2151.5 2164.3 2401.4 2430.5 2045.9 2423.6 2427.8 2443.5 2449.0 2200.4 2216.0 2419.3 2429.5 2430.3 2409.8 2422.7 2429.5 2433.1 2438.7 8987.5 8995.0 9048.9 9059.9 9103.0 5394.1 5545.4 5558.9 5566.4 5601.6 3621.7 3625.1 3640.1 3704.8 3710.2 1010.5 1043.7 1046.2 1047.9 1062.9 12022.8 12171.5 12211.5 12218.5 12246.9 4902.6 4926.9 5064.6 5079.7 5123.6 10449.0 10454.1 10461.2 10465.6 10474.6 5463.3 5490.2 5556.7 5558.7 5582.0 4485.3 4504.5 4990.6 5001.8 5091.8 4144.0 4144.3 4155.7 4186.6 4251.5 1329.9 1357.4 1364.4 1367.3 1368.9 811.7 909.2 946.4 971.7 976.1 4786.0 6053.5 6091.1 6130.9 6175.9 450.7 454.5 460.6 477.4 478.1 3955.6 4006.1 4028.2 4077.1 4113.6 8239.5 10803.7 11143.0 11184.0 11226.9 10845.0 10910.6 10995.3 11039.4 11152.4 7968.9 8379.5 8405.5 8497.3 8535.4 5673.2 5685.7 6046.3 6102.6 6251.1 10006.8 10161.8 10361.7 10399.5 10416.8 10265.2 10510.2 10543.9 10624.4 10639.7 7130.0 7486.0 7655.7 7723.8 7785.7 8532.3 8563.5 8608.9 8627.0 8634.6 6742.2 6996.1 7478.3 7539.2 7829.0 7415.7 7480.2 7510.0 7533.2 7595.4 6115.9 6921.2 7002.2 7010.5 7011.6 4577.8 4870.2 5035.4 5123.0 5231.8 4394.6 5054.2 5134.3 5240.8 5315.9 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4271.92 Median:4540.64 stddev:1280.11 (28.2%) stddev-sans-first:1248.54 13:25:21 INFO - PROCESS | 1952 | Values: 301.6 306.7 316.3 322.9 326.6 5151.6 5207.0 5295.7 5357.2 5439.4 4310.6 4463.0 4542.6 4555.6 5269.7 4108.3 4142.9 4154.9 4157.5 4160.3 3741.9 4080.6 4112.2 4149.2 4164.8 3972.6 4093.6 4128.0 4136.0 4167.0 3869.4 4129.9 4140.3 4155.5 4172.6 3227.8 3424.9 3603.6 3605.1 3638.8 4479.2 5130.1 5139.3 5172.8 5221.2 4149.6 4356.9 4538.7 4672.5 4746.8 5146.8 5497.9 5689.9 5696.6 5707.6 3808.2 3875.7 3957.6 3984.7 3990.4 4640.7 5200.4 5292.6 5591.5 6056.2 4035.1 4067.3 4078.6 4079.4 4083.1 3312.6 3416.2 3470.7 3882.9 4131.4 4021.3 4144.6 4341.1 4699.4 4776.8 5274.9 5358.2 5760.9 5860.2 5880.1 1117.9 1376.3 1402.2 1417.4 1418.0 799.7 842.3 857.5 872.7 873.8 4052.0 4179.2 4272.3 4495.7 4507.6 4484.0 4710.0 4793.5 4840.4 4954.9 4009.1 4090.8 4221.0 4463.7 4504.5 2152.3 2210.0 2373.8 2403.4 2674.5 5202.5 5234.5 5253.5 5261.6 5344.2 4317.8 4582.2 4605.1 4720.8 4996.8 4278.9 4576.0 4903.4 5078.3 5263.9 4269.7 4280.8 4425.4 4576.6 5035.5 4267.7 4298.8 4524.8 4819.7 4870.7 4298.9 4338.6 4784.0 5065.8 5218.9 3000.7 3548.5 3555.3 3574.6 3594.0 4419.2 4579.7 4810.7 4880.4 5616.9 5211.5 5537.6 5647.8 5776.7 5827.1 5457.3 5700.8 5740.9 5757.3 5764.6 4674.8 4680.8 5134.8 5178.9 5237.8 4760.8 5134.4 5270.9 5282.4 5757.4 4923.0 5008.9 5560.5 5623.8 5625.9 13:25:21 INFO - PROCESS | 1952 | 13:25:21 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:17805.74 Median:29923.13 stddev:25251.42 (84.4%) stddev-sans-first:25289.33 13:25:21 INFO - PROCESS | 1952 | Values: 631.9 651.2 688.3 705.7 738.7 13501.7 14807.7 15161.1 15382.4 16264.8 15457.3 15828.0 15832.4 15838.8 15848.9 899.8 906.5 950.1 950.4 961.5 917.8 922.5 932.1 936.6 938.0 933.0 934.1 981.0 994.7 1000.8 987.2 987.9 994.2 1010.5 1014.4 733.6 738.5 740.5 741.6 749.5 14570.3 15163.2 15194.2 15194.8 15206.8 12394.5 12478.6 12786.9 12865.2 13360.9 10030.3 10119.4 10166.3 10363.1 10442.9 3497.1 3551.2 3583.9 3597.0 3670.9 85540.6 85661.6 85730.0 86503.0 86886.6 7846.9 9620.1 9649.1 9706.2 9862.2 22247.8 22691.8 22705.1 22758.8 22819.3 7703.8 7766.1 7880.1 7891.0 7901.3 3605.3 4079.7 4156.1 4166.6 4185.9 3015.4 3515.7 3657.2 3666.0 3691.8 596.4 627.2 664.6 665.9 672.9 556.2 583.0 586.1 611.9 617.9 7979.7 8038.2 8092.4 8284.3 8340.9 574.8 586.3 593.8 602.3 617.2 3789.7 3925.3 3926.8 3929.1 3958.6 89690.0 90152.1 90991.0 91496.5 91763.1 80533.6 80815.6 80886.6 81340.0 82253.0 72836.0 73366.0 73967.0 74171.6 75022.6 7649.8 7657.3 7702.8 7763.7 7778.5 41833.4 41998.6 42577.5 45142.1 45837.3 30249.7 30359.9 30369.7 59260.0 60040.4 10887.1 11011.2 11032.2 11273.0 11548.1 11804.5 11898.4 12042.1 12202.3 12388.1 11258.3 11313.0 11341.0 11349.0 11760.7 11247.2 11462.5 11547.8 11845.8 11849.8 11020.2 11091.3 11219.5 11354.3 11504.9 9563.2 9573.8 9690.8 9815.6 9933.8 8904.1 9938.5 9943.5 9967.3 9979.0 13:25:21 INFO - PROCESS | 1952 | -------- Summary: end -------- 13:25:21 INFO - PROCESS | 1952 | 13:25:22 INFO - TEST-INFO | 1952: exit 0 13:25:22 INFO - TEST-OK | dromaeo_css | took 1112325ms 13:25:22 INFO - TEST-START | kraken 13:25:22 INFO - operating with platform_type : mac_ 13:25:22 INFO - Initialising browser for kraken test... 13:25:22 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpQI1_ld/profile http://localhost:52752/getInfo.html) 13:25:25 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 13:25:25 INFO - PROCESS | 1958 | colorDepth:24 13:25:25 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 13:25:25 INFO - PROCESS | 1958 | __metrics 13:25:25 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:25:26 INFO - TEST-INFO | 1958: exit 0 13:25:26 INFO - Browser initialized. 13:25:26 INFO - Running cycle 1/1 for kraken test... 13:25:26 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpQI1_ld/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:25:38 INFO - PROCESS | 1961 | RSS: Main: 250068992 13:25:38 INFO - PROCESS | 1961 | 13:25:41 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52752/tests/kraken/driver.html?testName=audio-beat-detection) 13:25:42 INFO - PROCESS | 1961 | RSS: Main: 261701632 13:25:42 INFO - PROCESS | 1961 | 13:25:44 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52752/tests/kraken/driver.html?testName=audio-dft) 13:25:45 INFO - PROCESS | 1961 | RSS: Main: 266383360 13:25:45 INFO - PROCESS | 1961 | 13:25:48 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52752/tests/kraken/driver.html?testName=audio-fft) 13:25:49 INFO - PROCESS | 1961 | RSS: Main: 266706944 13:25:49 INFO - PROCESS | 1961 | 13:25:51 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52752/tests/kraken/driver.html?testName=audio-oscillator) 13:25:52 INFO - PROCESS | 1961 | RSS: Main: 267382784 13:25:52 INFO - PROCESS | 1961 | 13:25:54 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52752/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:25:55 INFO - PROCESS | 1961 | RSS: Main: 267911168 13:25:55 INFO - PROCESS | 1961 | 13:25:58 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52752/tests/kraken/driver.html?testName=imaging-darkroom) 13:25:58 INFO - PROCESS | 1961 | RSS: Main: 268300288 13:25:58 INFO - PROCESS | 1961 | 13:26:02 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52752/tests/kraken/driver.html?testName=imaging-desaturate) 13:26:02 INFO - PROCESS | 1961 | RSS: Main: 268587008 13:26:02 INFO - PROCESS | 1961 | 13:26:05 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52752/tests/kraken/driver.html?testName=json-parse-financial) 13:26:06 INFO - PROCESS | 1961 | RSS: Main: 268550144 13:26:06 INFO - PROCESS | 1961 | 13:26:08 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52752/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:26:08 INFO - PROCESS | 1961 | RSS: Main: 268050432 13:26:08 INFO - PROCESS | 1961 | 13:26:10 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:26:11 INFO - PROCESS | 1961 | RSS: Main: 268587008 13:26:11 INFO - PROCESS | 1961 | 13:26:13 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:26:14 INFO - PROCESS | 1961 | RSS: Main: 268365824 13:26:14 INFO - PROCESS | 1961 | 13:26:17 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:26:18 INFO - PROCESS | 1961 | RSS: Main: 268582912 13:26:18 INFO - PROCESS | 1961 | 13:26:21 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:26:21 INFO - PROCESS | 1961 | RSS: Main: 268705792 13:26:21 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52752/tests/kraken/driver.html?testName=ai-astar) 13:26:23 INFO - PROCESS | 1961 | RSS: Main: 271093760 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | __start_tp_report 13:26:23 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 13:26:23 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 13:26:23 INFO - PROCESS | 1961 | |i|pagename|runs| 13:26:23 INFO - PROCESS | 1961 | |0;ai-astar;111;116;106;115;117;115;105;115;119;163 13:26:23 INFO - PROCESS | 1961 | |1;audio-beat-detection;156;122;147;204;111;179;170;127;174;151 13:26:23 INFO - PROCESS | 1961 | |2;audio-dft;207;234;195;199;198;193;196;195;196;194 13:26:23 INFO - PROCESS | 1961 | |3;audio-fft;75;87;79;179;83;80;48;75;133;50 13:26:23 INFO - PROCESS | 1961 | |4;audio-oscillator;79;83;146;105;116;107;132;114;68;106 13:26:23 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;88;86;134;107;88;86;89;86;89;86 13:26:23 INFO - PROCESS | 1961 | |6;imaging-darkroom;95;98;105;95;120;98;108;150;104;148 13:26:23 INFO - PROCESS | 1961 | |7;imaging-desaturate;88;93;96;124;90;91;89;91;91;87 13:26:23 INFO - PROCESS | 1961 | |8;json-parse-financial;60;62;61;60;59;60;62;136;78;58 13:26:23 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;64;62;66;68;71;72;69;69;75;81 13:26:23 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;86;85;99;84;87;88;111;109;80;99 13:26:23 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;162;159;154;163;154;161;162;138;211;153 13:26:23 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;166;167;166;172;152;208;226;228;166;168 13:26:23 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;67;65;66;68;69;67;65;65;69;64 13:26:23 INFO - PROCESS | 1961 | __end_tp_report 13:26:23 INFO - PROCESS | 1961 | __start_cc_report 13:26:23 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1058 13:26:23 INFO - PROCESS | 1961 | __end_cc_report 13:26:23 INFO - PROCESS | 1961 | __startTimestamp1463775983689__endTimestamp 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | ------- Summary: start ------- 13:26:23 INFO - PROCESS | 1961 | Number of tests: 14 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:118.20 Median:115.50 stddev:16.40 (14.2%) stddev-sans-first:17.18 13:26:23 INFO - PROCESS | 1961 | Values: 111.0 116.0 106.0 115.0 117.0 115.0 105.0 115.0 119.0 163.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:154.10 Median:163.00 stddev:28.76 (17.6%) stddev-sans-first:30.50 13:26:23 INFO - PROCESS | 1961 | Values: 156.0 122.0 147.0 204.0 111.0 179.0 170.0 127.0 174.0 151.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:200.70 Median:197.00 stddev:12.35 (6.3%) stddev-sans-first:12.88 13:26:23 INFO - PROCESS | 1961 | Values: 207.0 234.0 195.0 199.0 198.0 193.0 196.0 195.0 196.0 194.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:88.90 Median:77.00 stddev:39.23 (50.9%) stddev-sans-first:41.29 13:26:23 INFO - PROCESS | 1961 | Values: 75.0 87.0 79.0 179.0 83.0 80.0 48.0 75.0 133.0 50.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:139.00 stddev:23.90 (17.2%) stddev-sans-first:23.34 13:26:23 INFO - PROCESS | 1961 | Values: 79.0 83.0 146.0 105.0 116.0 107.0 132.0 114.0 68.0 106.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:87.00 stddev:15.44 (17.8%) stddev-sans-first:16.23 13:26:23 INFO - PROCESS | 1961 | Values: 88.0 86.0 134.0 107.0 88.0 86.0 89.0 86.0 89.0 86.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:112.10 Median:122.50 stddev:20.82 (17.0%) stddev-sans-first:21.15 13:26:23 INFO - PROCESS | 1961 | Values: 95.0 98.0 105.0 95.0 120.0 98.0 108.0 150.0 104.0 148.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:91.00 stddev:10.84 (11.9%) stddev-sans-first:11.28 13:26:23 INFO - PROCESS | 1961 | Values: 88.0 93.0 96.0 124.0 90.0 91.0 89.0 91.0 91.0 87.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:69.60 Median:60.50 stddev:24.02 (39.7%) stddev-sans-first:25.22 13:26:23 INFO - PROCESS | 1961 | Values: 60.0 62.0 61.0 60.0 59.0 60.0 62.0 136.0 78.0 58.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:5.50 (7.9%) stddev-sans-first:5.43 13:26:23 INFO - PROCESS | 1961 | Values: 64.0 62.0 66.0 68.0 71.0 72.0 69.0 69.0 75.0 81.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:86.50 stddev:10.93 (12.6%) stddev-sans-first:11.31 13:26:23 INFO - PROCESS | 1961 | Values: 86.0 85.0 99.0 84.0 87.0 88.0 111.0 109.0 80.0 99.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:161.70 Median:161.50 stddev:18.84 (11.7%) stddev-sans-first:19.99 13:26:23 INFO - PROCESS | 1961 | Values: 162.0 159.0 154.0 163.0 154.0 161.0 162.0 138.0 211.0 153.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:170.00 stddev:27.72 (16.3%) stddev-sans-first:28.80 13:26:23 INFO - PROCESS | 1961 | Values: 166.0 167.0 166.0 172.0 152.0 208.0 226.0 228.0 166.0 168.0 13:26:23 INFO - PROCESS | 1961 | 13:26:23 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 13:26:23 INFO - PROCESS | 1961 | Values: 67.0 65.0 66.0 68.0 69.0 67.0 65.0 65.0 69.0 64.0 13:26:23 INFO - PROCESS | 1961 | -------- Summary: end -------- 13:26:23 INFO - PROCESS | 1961 | 13:26:24 INFO - TEST-INFO | 1961: exit 0 13:26:24 INFO - TEST-OK | kraken | took 61896ms 13:26:24 INFO - SUITE-END | took 1174s 13:26:24 INFO - Completed test suite (00:19:34) 13:26:24 INFO - javascript benchmark 13:26:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3463.2554000889804, 3492.8777492787754, 3518.1204698825295, 3525.6121826599056, 3538.8230588470587, 4676.275177347512, 5654.74553645086, 5705.828825135248, 5745.138229265613, 5802.158686990384, 5719.227904232927, 5726.885462290752, 5755.079187330028, 5765.855853603652, 5856.033754430514, 4397.274449715793, 5129.9230511542355, 5218.6868787872845, 5239.0, 5303.124984377573, 4881.438634557018, 5302.125149350371, 5324.7071411072375, 5371.865703357428, 5388.164834450647, 4331.328644060176, 5141.177411614142, 5151.150060240056, 5264.0, 5285.973570132163, 5232.2674825524455, 5243.711595862226, 5248.763805628756, 5275.103232450492, 5314.840554783363, 5052.040112378636, 5192.71440070796, 5202.193659982709, 5203.791848326063, 5217.5565076968505, 5628.0, 5658.603897727161, 5669.744861481243, 5754.4533269339345, 5759.452851979097, 5586.24585680932, 5892.734826932799, 6241.251049873998, 6241.28225254097, 6321.87356252877, 6188.47397971173, 6191.226096737908, 6215.378462153749, 6280.623162610258, 6287.559870809046, 5867.530597552186, 5917.171595976567, 5929.407059294079, 5936.28764548257, 5961.970190149065, 6277.435030847246, 6287.9056814147825, 6297.2128483939505, 6303.180586523723, 6470.22357317125, 6110.7861224856915, 6143.051335359052, 6150.969245153746, 6206.782762603287, 6207.81376558704, 6144.709610981744, 6234.937650623436, 6262.780802671885, 6276.748930042747, 6278.46633036197, 5375.247465354853, 6016.278046634432, 6150.231221097363, 6271.62370257786, 6276.9372306276355, 6144.77104579086, 6165.630062196283, 6169.2288463942, 6170.012797952396, 6283.0, 4989.201727723619, 5016.44819069902, 5019.774110165052, 5029.0947629427055, 5033.19468884984, 4437.5784300491405, 4492.752898590544, 4496.145732310916, 4496.460424749051, 4533.750643714561, 5634.464725851038, 5678.488935995781, 5686.118651609007, 5781.739821708034, 5784.363719990798, 6254.3745625437095, 6260.874782504416, 6268.4671802896355, 6269.279032911274, 6299.590526615755, 5614.2701448811395, 5774.711264436762, 5783.971080144573, 5809.24479817621, 5809.970950145223, 5420.620556561082, 5442.700651464128, 5485.314335708036, 5519.0, 5520.751566179531, 6465.191851018622, 6475.514336424787, 6477.352264773579, 6496.577722448026, 6502.934970650422, 6198.194234749454, 6204.5036397089025, 6246.968765156145, 6286.214223222098, 6293.37066293367, 6280.874382512416, 6388.329225431263, 6445.387688169804, 6462.159919210472, 6470.547061705824, 6247.9062814056915, 6272.529560283088, 6291.150694656163, 6295.433410993032, 6301.243850737941, 6171.043488259327, 6215.720292586754, 6240.968795155995, 6251.749930002749, 6282.403171698681, 4963.8262660806695, 6120.632762034292, 6130.049842274455, 6171.59884607499, 6260.812175634738, 5196.870078247923, 6216.409441103088, 6218.0, 6225.501959843111, 6229.408206220402, 5041.621878359211, 5900.203472531153, 6043.607165534227, 6075.696215189311, 6131.110988906673, 6104.883608894006, 6105.277797093486, 6143.385661433821, 6192.195014648067, 6206.751729930752, 6082.939170608237, 6092.299385570539, 6096.493844838371, 6104.786332478342, 6139.29398119222], "value": 5713.162531202917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.9101961646902, 515.2991930973876, 558.4276116980095, 560.0, 570.4808624152023, 37077.62922370776, 37803.62196378035, 39711.80144099283, 39733.60266397335, 39846.00384990377, 40574.18851622973, 41155.0, 41241.0, 41330.58669413305, 41382.58617413825, 8208.220219079178, 9433.95283023584, 9677.90322096777, 9685.370450920704, 9703.07820757027, 9482.146606805387, 9493.860736711604, 9590.088941550577, 9615.711528654152, 9650.855237171412, 9564.617415303379, 9623.518824058805, 9639.180669643054, 9640.75898102546, 9645.373050751714, 9541.141297283279, 9548.331616786827, 9572.952135239315, 9620.134187923086, 9630.133288004043, 9608.327417080807, 9731.90268097324, 9732.513374331254, 9738.0, 9762.853557196648, 31697.683023169935, 38395.23209535822, 38513.61486385128, 38828.0, 38876.416853747214, 2793.622860913771, 3003.849807509616, 3105.5186446100893, 3160.2415420299194, 3184.7452203823636, 27135.379195713154, 27185.048523301786, 27299.044533441433, 27512.862435687694, 28026.0, 2661.680598328194, 2663.5605125154325, 2677.54481738105, 2715.551933930919, 2748.6426764520684, 413896.0, 414589.0, 417013.8298616975, 419020.0, 419659.0, 34574.13564660904, 35532.0, 35628.0, 35641.64358356435, 35753.39109740068, 36774.8161259192, 36983.445248321295, 37265.44101838474, 37923.62076379256, 38220.61779382171, 14815.0, 16566.254518546695, 16791.748123778154, 16958.660826783394, 17008.744868826976, 12729.363531823372, 12884.549040783528, 12896.548620798227, 12911.22532648044, 12940.417681204368, 17036.0, 19495.610087798166, 20364.083616237305, 20475.4881127973, 20673.276435324693, 7121.323474269936, 7179.964100179465, 7472.692278851179, 7646.617669116522, 7881.5271083734015, 743.7582785594703, 941.7928055827707, 966.3911735606663, 1066.2536224642781, 1092.907102896247, 29569.0, 30008.849955750517, 30017.849910750745, 30445.543316850264, 30703.692963070083, 943.0522325063405, 1108.3737688206138, 1162.3723189477587, 1180.2033627301537, 1208.1542919956066, 12915.739745544492, 13116.737665246641, 14624.780628290584, 15272.465463708717, 15422.46021389246, 540448.0, 684593.0, 685482.5725871339, 685731.0, 690660.0, 48612.0, 53929.38211853651, 58394.41605583975, 59025.70487147623, 60591.09113363303, 1251.217988757027, 1456.4611093895326, 1462.4808193091512, 1494.5516345096428, 1504.8344682084962, 29496.410071798873, 29737.0, 29969.850150749106, 30478.69521304847, 30510.0, 53071.20393194028, 53226.73386633042, 53279.0, 53438.66403339947, 53665.0, 581996.0, 587177.0, 595369.0, 595647.0, 600508.0, 26257.474850503266, 26407.019473539178, 27296.590551142144, 27517.587236191073, 27675.72324276812, 39153.608463915, 44497.33254001128, 45410.0, 45593.77203113963, 46902.06195876132, 37022.889313320644, 38051.80974095112, 38508.614913850506, 39014.0, 39398.0, 34142.487862681584, 36427.0, 37867.053323667125, 38152.42771358376, 38953.80523097367, 37527.061823454635, 37838.810805945795, 37857.432138518496, 38619.0, 39412.802935985135, 32541.67458325481, 33850.83074584611, 33938.012536234266, 34302.48546271758, 34368.82815585906, 23987.52024959456, 25193.37016574527, 25216.243512694648, 25422.110226141995, 25631.487370252864], "value": 19089.927694892798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.1772305973202, 380.54904937648877, 391.9686425085993, 395.3318891074085, 407.977561234132, 17838.732419013726, 20287.49281267969, 20580.48548786281, 22600.095996160155, 22643.207487737913, 12054.216475929059, 12496.625101246955, 12499.625011249655, 12757.0, 21208.4697882553, 4953.0, 5471.644343117695, 5648.717564121798, 5665.518430933355, 5672.886542269152, 3921.274564205617, 4856.708597484162, 6173.197484327031, 6193.535484838632, 6228.657423841687, 5657.688827114507, 6225.2529696436495, 6225.62646241227, 6238.750449982018, 6244.656543890084, 5897.439743224387, 6024.638521688691, 6024.819255422344, 6026.156338112668, 6041.365656606037, 5720.742566584514, 5774.364819869811, 5850.619709718854, 6071.817845464643, 6078.45293923549, 13769.931150344284, 13941.512047078304, 14014.089084209494, 14138.868890488737, 14251.287435628281, 2189.8576592521435, 2355.658429527709, 2604.4400453902313, 2752.6696796384363, 2819.7603203727504, 9519.952400238024, 10215.94892025535, 10303.793924121475, 10404.583816647326, 10488.632897848538, 1777.7777777777778, 1823.8085001074828, 1826.6985947318676, 1832.5418645338666, 1835.3025850176848, 83455.0, 85528.57235713844, 116475.8352416482, 137796.9330460034, 140396.0, 9499.667511637059, 11498.54005839757, 11735.823962640567, 11948.940255298841, 12017.63947081589, 12887.806682899763, 15434.536963891102, 15455.0, 15480.922595387176, 15498.845011549965, 6746.831329216809, 7749.535027898344, 8697.565121743888, 8758.781030474289, 8836.602352894006, 8576.485410875368, 8592.828143437222, 8599.269062129664, 8661.566921654017, 8708.43395179324, 4651.86044418668, 4763.261694437368, 4764.2615394613895, 4770.785314660849, 4866.343043689128, 1827.1229809691156, 1913.3685883658359, 2040.9897950510153, 2047.2015913793632, 2093.8324934005245, 852.7313896122826, 922.257582645963, 997.2149851765259, 1013.6806905824642, 1041.3491567770143, 17071.743923841153, 20185.49536261576, 20268.0, 20690.793092069187, 21153.68269475959, 895.1272509303377, 910.8588168833842, 927.5779520318239, 939.4504215034145, 949.3971328206638, 10411.895881041244, 10783.352998820095, 10869.29349592274, 10882.673519794418, 10899.509522071527, 588735.0, 591593.0, 593005.0, 606055.0, 628971.0, 111287.0, 141382.0, 141461.0, 141558.0, 144162.0, 1012.5696578953915, 1027.4041056187393, 1037.2946396450636, 1043.113353649392, 1043.3114144664485, 16327.918360408123, 16537.586560336087, 18227.0, 18475.445736627924, 18595.97722125242, 41026.58973410228, 49759.0, 50407.747961259956, 52077.0, 52590.15934442276, 60193.0, 76818.61590692011, 77408.61295693486, 78336.82494762697, 78676.0, 8186.0, 8276.958615207126, 8288.33693304522, 8421.157884211774, 8533.359998000298, 8407.663693452194, 12464.0, 12829.037822163189, 12845.293508857103, 12922.353882305659, 7403.5928023959195, 9697.369670971364, 10869.913008699066, 10974.176936729618, 11301.943490282496, 7658.578778167254, 10661.840072399076, 10723.30298530593, 10724.249302548747, 11285.266457680225, 8149.225823546753, 10607.469626518798, 10830.945845270724, 11763.647090587296, 11842.111841612086, 8332.583370831313, 8378.664853405795, 8441.0, 8443.071262161035, 8476.660933562589, 8480.321574274167, 10268.64059757905, 10892.891071089187, 11134.33194008362, 11174.106071514097], "value": 9255.749057607769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1621.1894052973516, 1657.5524608355745, 1951.599922015987, 2009.5880344529373, 2016.233831144164, 8221.177882211776, 8829.558522073903, 8852.690155844546, 9378.99999999999, 9384.953075234624, 7927.088384835745, 8856.715776212446, 8907.866382004275, 8926.553672316391, 9091.681791137311, 2419.600765873633, 2442.2795275393714, 2444.4622183119686, 2448.5837407640656, 2452.766987136219, 2059.6498595238772, 2151.5481748832685, 2164.2966036038274, 2401.399650087478, 2430.538197742432, 2045.864545177426, 2423.563758523465, 2427.78149966503, 2443.450223699671, 2448.9755102449017, 2200.3508964855405, 2216.002798740565, 2419.2742177346813, 2429.465517586137, 2430.2952143878256, 2409.8192635552405, 2422.7213870404953, 2429.4898071405028, 2433.124075332879, 2438.7439318871616, 8987.505687187202, 8994.955025224897, 9048.909510904938, 9059.864102038475, 9103.044180361034, 5394.109971854639, 5545.445455454463, 5558.888822223574, 5566.443355664442, 5601.60788744788, 3621.6740493355724, 3625.148090198801, 3640.0899775056237, 3704.833282502294, 3710.2023065040876, 1010.540204207084, 1043.7390652336917, 1046.1892033673964, 1047.9266451348408, 1062.941538215398, 12022.819657705142, 12171.452284647123, 12211.511539538407, 12218.450169742382, 12246.938765306117, 4902.558769710672, 4926.852194434173, 5064.620153488503, 5079.74601269935, 5123.641345105807, 10449.00734430228, 10454.11140053104, 10461.215408844368, 10465.581376744996, 10474.633387831389, 5463.344398672185, 5490.203920431595, 5556.694176868433, 5558.749856256478, 5581.972090139605, 4485.327200919888, 4504.504504504502, 4990.5508504234795, 5001.824936127218, 5091.821786237464, 4143.958560414418, 4144.291617591278, 4155.688323375759, 4186.644135248477, 4251.489821221411, 1329.9335033248299, 1357.3688234970787, 1364.36557000995, 1367.2616786934943, 1368.9247091409868, 811.7483580090191, 909.1771946388529, 946.3517490519016, 971.6696323250129, 976.1409959235828, 4785.976070119627, 6053.51571874249, 6091.0558863376245, 6130.908036379458, 6175.876482470326, 450.7430764464224, 454.5363728996405, 460.5762698317556, 477.4032459425718, 478.07015355133933, 3955.6044395560216, 4006.1186538961383, 4028.1540876415693, 4077.1030373318417, 4113.568075352105, 8239.546824924566, 10803.729906752395, 11143.0, 11184.0, 11226.943865280622, 10845.023947844575, 10910.563577456971, 10995.34027958325, 11039.39283339424, 11152.386618736047, 7968.880466793003, 8379.49723016621, 8405.453645513022, 8497.320214382835, 8535.445196062235, 5673.177389278449, 5685.692290773062, 6046.334903160737, 6102.633841969496, 6251.062340648939, 10006.799864002534, 10161.84757228657, 10361.689149325231, 10399.532021059222, 10416.843747343948, 10265.2301077418, 10510.211734120125, 10543.94728026355, 10624.36253824773, 10639.734006649896, 7130.001799748143, 7485.962570187115, 7655.732049378245, 7723.845523089394, 7785.688572457038, 8532.317414606692, 8563.486190828571, 8608.91391086081, 8627.0, 8634.61144248523, 6742.224644165984, 6996.090508233897, 7478.326950574475, 7539.170691224071, 7828.960855195687, 7415.740449084472, 7480.214577469287, 7510.0, 7533.171351151478, 7595.354394876497, 6115.877682446415, 6921.2386637470845, 7002.1947476040905, 7010.47421443404, 7011.579305241702, 4577.843672985701, 4870.220764677634, 5035.446100928822, 5123.0, 5231.790728370823, 4394.582514661102, 5054.16606259963, 5134.255532947776, 5240.764165612481, 5315.946840531546], "value": 4603.959933780712, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.55370052322564, 306.65654466996966, 316.2631069607814, 322.88214801597417, 326.59502217250616, 5151.613628977832, 5207.0106679730825, 5295.735213239342, 5357.2232026356205, 5439.4288599697, 4310.612044915957, 4462.991653755815, 4542.6138778203895, 4555.612772914306, 5269.710165940881, 4108.3221268490815, 4142.917141657165, 4154.854580089697, 4157.52188498322, 4160.334346504553, 3741.86903458379, 4080.5720596245337, 4112.198121366319, 4149.211649786546, 4164.812583433738, 3972.602739726033, 4093.631573158415, 4127.958720412803, 4136.048708796979, 4167.020750123718, 3869.413540448431, 4129.91740165198, 4140.3168477201225, 4155.522114956789, 4172.645325147366, 3227.8386080696105, 3424.938269136576, 3603.621619729943, 3605.0987253186704, 3638.8362523686496, 4479.238529449969, 5130.127878260705, 5139.329717841716, 5172.844814655567, 5221.242919776649, 4149.605787450188, 4356.869293921156, 4538.662588766788, 4672.532746725335, 4746.810127594892, 5146.819861304836, 5497.890042199134, 5689.923178039986, 5696.572757043238, 5707.571932105108, 3808.1720774028345, 3875.65119139276, 3957.6042395760483, 3984.721069525134, 3990.4213888986224, 4640.651951103647, 5200.401953775327, 5292.603054770908, 5591.496765291103, 6056.242969628796, 4035.1122752994293, 4067.2678917794487, 4078.5921407858978, 4079.389190917815, 4083.0813067060267, 3312.585926759155, 3416.24842534642, 3470.704990075872, 3882.9127844203667, 4131.400946862688, 4021.2962731521866, 4144.570123307454, 4341.08837144197, 4699.412573428322, 4776.785044672929, 5274.947250527445, 5358.2498450217, 5760.897272495593, 5860.150278209636, 5880.147378630075, 1117.8938000889902, 1376.3256004557895, 1402.2401885633465, 1417.4259424932918, 1417.9932248103962, 799.696115476127, 842.2672275120684, 857.524074138859, 872.7381785464336, 873.7509809704204, 4051.9870032491876, 4179.2477354076555, 4272.33778764292, 4495.685302028826, 4507.63938884895, 4484.031632005634, 4710.0, 4793.544613261734, 4840.394950631171, 4954.851354459372, 4009.0979529605606, 4090.793216001287, 4221.016122422457, 4463.66522510813, 4504.477492604152, 2152.3096129837704, 2209.9502736200498, 2373.798227150677, 2403.375122468171, 2674.4784766970256, 5202.479752024768, 5234.502722241381, 5253.452075743261, 5261.631685781958, 5344.156266088194, 4317.76943571087, 4582.152301824173, 4605.0559635274685, 4720.843393368578, 4996.752111127796, 4278.893027674333, 4576.0, 4903.362562866876, 5078.289039534542, 5263.868403289948, 4269.679774016899, 4280.838176720736, 4425.446819147606, 4576.610988065919, 5035.4712755161645, 4267.701260911831, 4298.76356800379, 4524.819007239674, 4819.7108173509705, 4870.707757534559, 4298.892527686708, 4338.58783415575, 4783.976080119577, 5065.784211789218, 5218.947810521846, 3000.73968933053, 3548.4677298405445, 3555.2711694103164, 3574.57105147384, 3593.9810559130456, 4419.226635338867, 4579.656525760648, 4810.742492893744, 4880.389951256093, 5616.887662246814, 5211.530962213419, 5537.5569954404355, 5647.774089036392, 5776.682282474504, 5827.125931110368, 5457.263269458573, 5700.828975130753, 5740.933264024864, 5757.280339957505, 5764.587676019402, 4674.836380726658, 4680.7893644786745, 5134.7946082156295, 5178.948210517847, 5237.790488380422, 4760.809567617256, 5134.358205224347, 5270.947290527046, 5282.418933917343, 5757.395473475225, 4922.956972856225, 5008.899822003466, 5560.499555040065, 5623.803166889139, 5625.887482250414], "value": 3873.587001844598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.8704665543563, 651.244556314675, 688.328879342641, 705.7106586299618, 738.68236658237, 13501.729965400686, 14807.741341985931, 15161.090334579932, 15382.384704611803, 16264.75602865955, 15457.30442130104, 15827.97118187317, 15832.44586439475, 15838.76241856373, 15848.920755396206, 899.8425275576748, 906.5104843384597, 950.1211379473993, 950.363256618064, 961.4904100826539, 917.7980844214262, 922.4649703172144, 932.1051790281338, 936.5504557812254, 937.9822892162013, 933.0016881936331, 934.0845970948475, 980.9803803923917, 994.6618149829056, 1000.7548150703088, 987.210231814549, 987.8715766950287, 994.1847684898386, 1010.540204207084, 1014.3761586624173, 733.6405161470894, 738.5199620246829, 740.496462405564, 741.6106544064355, 749.4940914882487, 14570.271486425743, 15163.166025868573, 15194.240287985667, 15194.772078418833, 15206.783457323387, 12394.504219831197, 12478.563250286197, 12786.936065319614, 12865.228086314757, 13360.86639133606, 10030.29882963342, 10119.443430611313, 10166.288359814816, 10363.067323940806, 10442.895571044344, 3497.1257185703575, 3551.183227857581, 3583.910402239939, 3597.0, 3670.853165873362, 85540.57229713812, 85661.57169214114, 85730.0, 86503.0, 86886.5655671724, 7846.921530784619, 9620.13418792298, 9649.13157815786, 9706.223502119814, 9862.211023118276, 22247.777522224893, 22691.773082268966, 22705.09179632796, 22758.772412275997, 22819.315420537412, 7703.768886933401, 7766.106897706723, 7880.093789214199, 7890.9605451972375, 7901.288884000468, 3605.260921511084, 4079.653229475528, 4156.085661154541, 4166.604172603597, 4185.853495127717, 3015.3969206158854, 3515.7011654009184, 3657.213699054716, 3665.9816700916326, 3691.8338674759702, 596.4244504053609, 627.1533429869639, 664.5647101148755, 665.9334066593302, 672.9259781424039, 556.2157358125024, 582.977647566214, 586.1383765864173, 611.8898598252268, 617.8918689229367, 7979.72070977513, 8038.196180381915, 8092.3930705197345, 8284.337253019745, 8340.874886876702, 574.8218052403769, 586.2876604925036, 593.801076639322, 602.3103546439303, 617.200725061047, 3789.6778773804535, 3925.2542017017245, 3926.842926282917, 3929.057026313664, 3958.5801456337445, 89690.0, 90152.09847901568, 90991.0, 91496.54251728699, 91763.08236917679, 80533.59733201297, 80815.59592202002, 80886.59556702296, 81340.0, 82253.0, 72836.0, 73366.0, 73967.0, 74171.62914185395, 75022.62488687522, 7649.808754781175, 7657.34912532441, 7702.807429814299, 7763.650635721293, 7778.455508114604, 41833.372499411926, 41998.58001419947, 42577.50978715854, 45142.09715805731, 45837.31244031276, 30249.697503024687, 30359.93740219082, 30369.696303036686, 59259.96290315199, 60040.39959600523, 10887.074598658886, 11011.174161937726, 11032.227744057838, 11273.041791447818, 11548.133889958119, 11804.527818887149, 11898.405079746151, 12042.096842736655, 12202.32887191213, 12388.070894682754, 11258.268212566158, 11313.0, 11341.0, 11349.0, 11760.70598235051, 11247.212695111264, 11462.484188211718, 11547.769044619228, 11845.822312665488, 11849.822252666045, 11020.193082054813, 11091.260979604118, 11219.495122719334, 11354.26197297173, 11504.907033831772, 9563.187129094105, 9573.808523829344, 9690.806183876142, 9815.607375704893, 9933.751656208653, 8904.109589041043, 9938.503074846374, 9943.502824858584, 9967.302288839712, 9979.01771064314], "value": 6892.091204745946, "unit": "score"}], "name": "dromaeo_css", "value": 7062.455851686044, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 106.0, 115.0, 117.0, 115.0, 105.0, 115.0, 119.0, 163.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 147.0, 204.0, 111.0, 179.0, 170.0, 127.0, 174.0, 151.0], "value": 154.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 234.0, 195.0, 199.0, 198.0, 193.0, 196.0, 195.0, 196.0, 194.0], "value": 200.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 87.0, 79.0, 179.0, 83.0, 80.0, 48.0, 75.0, 133.0, 50.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 146.0, 105.0, 116.0, 107.0, 132.0, 114.0, 68.0, 106.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 134.0, 107.0, 88.0, 86.0, 89.0, 86.0, 89.0, 86.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 105.0, 95.0, 120.0, 98.0, 108.0, 150.0, 104.0, 148.0], "value": 112.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 96.0, 124.0, 90.0, 91.0, 89.0, 91.0, 91.0, 87.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 60.0, 59.0, 60.0, 62.0, 136.0, 78.0, 58.0], "value": 69.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 68.0, 71.0, 72.0, 69.0, 69.0, 75.0, 81.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 99.0, 84.0, 87.0, 88.0, 111.0, 109.0, 80.0, 99.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 154.0, 163.0, 154.0, 161.0, 162.0, 138.0, 211.0, 153.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 172.0, 152.0, 208.0, 226.0, 228.0, 166.0, 168.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 68.0, 69.0, 67.0, 65.0, 65.0, 69.0, 64.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1609.7, "alertThreshold": 2.0}]} 13:26:24 INFO - Return code: 0 13:26:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:26:25 ERROR - # TBPL SUCCESS # 13:26:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:26:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:26:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:26:25 INFO - 2016-05-20 13:26:25.017 screenresolution[1966:28801] starting screenresolution argv=screenresolution get 13:26:25 INFO - 2016-05-20 13:26:25.020 screenresolution[1966:28801] Display 0: 1600x1200x32@60 13:26:25 INFO - 2016-05-20 13:26:25.026 screenresolution[1967:28804] starting screenresolution argv=screenresolution list 13:26:25 INFO - Available Modes on Display 0 13:26:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:26:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:26:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:26:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:26:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:26:25 INFO - Intel Iris: 13:26:25 INFO - Chipset Model: Intel Iris 13:26:25 INFO - Type: GPU 13:26:25 INFO - Bus: Built-In 13:26:25 INFO - VRAM (Dynamic, Max): 1536 MB 13:26:25 INFO - Vendor: Intel (0x8086) 13:26:25 INFO - Device ID: 0x0a2e 13:26:25 INFO - Revision ID: 0x0009 13:26:25 INFO - Displays: 13:26:25 INFO - Display: 13:26:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:26:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:26:25 INFO - Display Serial Number: 1600x1200 60 13:26:25 INFO - Main Display: Yes 13:26:25 INFO - Mirror: Off 13:26:25 INFO - Online: Yes 13:26:25 INFO - Rotation: Supported 13:26:25 INFO - Return code: 0 13:26:25 INFO - Running post-action listener: _resource_record_post_action 13:26:25 INFO - ##### 13:26:25 INFO - ##### Finished run-tests step (success) 13:26:25 INFO - ##### 13:26:25 INFO - Running post-run listener: _resource_record_post_run 13:26:26 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 651264; Write bytes: 698349568; Read time: 4; Write time: 1928 13:26:26 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388545024; Write bytes: 384267264; Read time: 28659; Write time: 775 13:26:26 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 651264; Write bytes: 298882048; Read time: 4; Write time: 1114 13:26:26 INFO - Running post-run listener: _upload_blobber_files 13:26:26 INFO - Blob upload gear active. 13:26:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:26:26 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 13:26:26 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:26:26 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:26:26 INFO - (blobuploader) - INFO - Open directory for files ... 13:26:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:26:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:26:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:27 INFO - (blobuploader) - INFO - Done attempting. 13:26:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:26:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:26:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:28 INFO - (blobuploader) - INFO - Done attempting. 13:26:28 INFO - (blobuploader) - INFO - Iteration through files over. 13:26:28 INFO - Return code: 0 13:26:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:26:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:26:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2385583d4da838f2977c50d7eb713e5eca3944d08467a191fe2c4e424a6dcc9ff991b61ae193667aa7cde9f226a4e0eae503484b0a20d0273da458219260bb38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/638943fdfee5c03226b5eddb140c2451fdb91c82fe3823789a15c864ab5b7b4cdb1fe951a471585531ebf3926e7b5be9de96514d86dcb345b4d4b4236731673d"} 13:26:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:26:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:26:28 INFO - Contents: 13:26:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2385583d4da838f2977c50d7eb713e5eca3944d08467a191fe2c4e424a6dcc9ff991b61ae193667aa7cde9f226a4e0eae503484b0a20d0273da458219260bb38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/638943fdfee5c03226b5eddb140c2451fdb91c82fe3823789a15c864ab5b7b4cdb1fe951a471585531ebf3926e7b5be9de96514d86dcb345b4d4b4236731673d"} 13:26:28 INFO - Running post-run listener: copy_logs_to_upload_dir 13:26:28 INFO - Copying logs to upload dir... 13:26:28 INFO - mkdir: /builds/slave/test/build/upload/logs 13:26:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.532042 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-20 13:26:28.822285) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:28.826243) ========= 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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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/Larch/sha512/2385583d4da838f2977c50d7eb713e5eca3944d08467a191fe2c4e424a6dcc9ff991b61ae193667aa7cde9f226a4e0eae503484b0a20d0273da458219260bb38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/638943fdfee5c03226b5eddb140c2451fdb91c82fe3823789a15c864ab5b7b4cdb1fe951a471585531ebf3926e7b5be9de96514d86dcb345b4d4b4236731673d"} build_url:https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2385583d4da838f2977c50d7eb713e5eca3944d08467a191fe2c4e424a6dcc9ff991b61ae193667aa7cde9f226a4e0eae503484b0a20d0273da458219260bb38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/638943fdfee5c03226b5eddb140c2451fdb91c82fe3823789a15c864ab5b7b4cdb1fe951a471585531ebf3926e7b5be9de96514d86dcb345b4d4b4236731673d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:28.855517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:28.855873) ========= 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.Nao23kY6i8/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.PqgB8Cyp4g/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.005021 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:28.904767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:26:28.905093) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:26:28.905453) ========= ========= Total master_lag: 0.17 =========