builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0182 starttime: 1462574448.65 results: success (0) buildid: 20160506124403 builduid: 6435578bbb094c5bae14ef6d166f1853 revision: 1d32762a203507d4f0e7743b8a329ab2bbba9799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.649008) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.649465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.649732) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.711291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.711674) ========= 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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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-06 15:40:48-- 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% 145M=0s 2016-05-06 15:40:48 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.839255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.839568) ========= 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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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.027172 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:48.887821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 15:40:48.888196) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1d32762a203507d4f0e7743b8a329ab2bbba9799 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1d32762a203507d4f0e7743b8a329ab2bbba9799 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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-06 15:40:48,958 truncating revision to first 12 chars 2016-05-06 15:40:48,958 Setting DEBUG logging. 2016-05-06 15:40:48,958 attempt 1/10 2016-05-06 15:40:48,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1d32762a2035?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 15:40:49,980 unpacking tar archive at: fx-team-1d32762a2035/testing/mozharness/ program finished with exit code 0 elapsedTime=1.265373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 15:40:50.170495) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:50.170823) ========= script_repo_revision: 1d32762a203507d4f0e7743b8a329ab2bbba9799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:50.171288) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:50.171549) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 15:40:50.185604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 15:40:50.186028) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:40:50 INFO - MultiFileLogger online at 20160506 15:40:50 in /builds/slave/test 15:40:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:40:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:40:50 INFO - {'append_to_log': False, 15:40:50 INFO - 'base_work_dir': '/builds/slave/test', 15:40:50 INFO - 'blob_upload_branch': 'Fx-Team', 15:40:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:40:50 INFO - 'branch': 'Fx-Team', 15:40:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:40:50 INFO - 'config_files': ('talos/mac_config.py',), 15:40:50 INFO - 'default_actions': ('clobber', 15:40:50 INFO - 'read-buildbot-config', 15:40:50 INFO - 'download-and-extract', 15:40:50 INFO - 'populate-webroot', 15:40:50 INFO - 'create-virtualenv', 15:40:50 INFO - 'install', 15:40:50 INFO - 'run-tests'), 15:40:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:40:50 INFO - 'download_minidump_stackwalk': True, 15:40:50 INFO - 'download_symbols': 'ondemand', 15:40:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:40:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:40:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:40:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:40:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:40:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:40:50 INFO - 'installer_path': 'installer.exe', 15:40:50 INFO - 'log_level': 'info', 15:40:50 INFO - 'log_name': 'talos', 15:40:50 INFO - 'log_to_console': True, 15:40:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:40:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:40:50 INFO - 'opt_config_files': (), 15:40:50 INFO - 'pip_index': False, 15:40:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:40:50 INFO - 'cmd': ('bash', 15:40:50 INFO - '-c', 15:40:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:40:50 INFO - 'enabled': True, 15:40:50 INFO - 'halt_on_failure': False, 15:40:50 INFO - 'name': 'check_screen_resolution'},), 15:40:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:40:50 INFO - 'cmd': ('bash', 15:40:50 INFO - '-c', 15:40:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:40:50 INFO - 'enabled': True, 15:40:50 INFO - 'halt_on_failure': False, 15:40:50 INFO - 'name': 'check_screen_resolution'},), 15:40:50 INFO - 'run_cmd_checks_enabled': True, 15:40:50 INFO - 'sps_profile': False, 15:40:50 INFO - 'sps_profile_interval': 0, 15:40:50 INFO - 'suite': 'dromaeojs-e10s', 15:40:50 INFO - 'system_bits': '32', 15:40:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:40:50 INFO - 'title': 't-yosemite-r7-0182', 15:40:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:40:50 INFO - 'use_talos_json': True, 15:40:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:40:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:40:50 INFO - 'work_dir': 'build'} 15:40:50 INFO - ##### 15:40:50 INFO - ##### Running clobber step. 15:40:50 INFO - ##### 15:40:50 INFO - Running pre-action listener: _resource_record_pre_action 15:40:50 INFO - Running main action method: clobber 15:40:50 INFO - rmtree: /builds/slave/test/build 15:40:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:40:52 INFO - Running post-action listener: _resource_record_post_action 15:40:52 INFO - ##### 15:40:52 INFO - ##### Finished clobber step (success) 15:40:52 INFO - ##### 15:40:52 INFO - ##### 15:40:52 INFO - ##### Running read-buildbot-config step. 15:40:52 INFO - ##### 15:40:52 INFO - Running pre-action listener: _resource_record_pre_action 15:40:52 INFO - Running main action method: read_buildbot_config 15:40:52 INFO - Using buildbot properties: 15:40:52 INFO - { 15:40:52 INFO - "project": "", 15:40:52 INFO - "product": "firefox", 15:40:52 INFO - "script_repo_revision": "production", 15:40:52 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 15:40:52 INFO - "repository": "", 15:40:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 15:40:52 INFO - "buildid": "20160506124403", 15:40:52 INFO - "pgo_build": "False", 15:40:52 INFO - "basedir": "/builds/slave/test", 15:40:52 INFO - "buildnumber": 0, 15:40:52 INFO - "slavename": "t-yosemite-r7-0182", 15:40:52 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:40:52 INFO - "platform": "yosemite_r7", 15:40:52 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 15:40:52 INFO - "branch": "fx-team", 15:40:52 INFO - "repo_path": "integration/fx-team", 15:40:52 INFO - "revision": "1d32762a203507d4f0e7743b8a329ab2bbba9799", 15:40:52 INFO - "stage_platform": "macosx64", 15:40:52 INFO - "builduid": "6435578bbb094c5bae14ef6d166f1853", 15:40:52 INFO - "slavebuilddir": "test" 15:40:52 INFO - } 15:40:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:40:52 INFO - Running post-action listener: _resource_record_post_action 15:40:52 INFO - ##### 15:40:52 INFO - ##### Finished read-buildbot-config step (success) 15:40:52 INFO - ##### 15:40:52 INFO - ##### 15:40:52 INFO - ##### Running download-and-extract step. 15:40:52 INFO - ##### 15:40:52 INFO - Running pre-action listener: _resource_record_pre_action 15:40:52 INFO - Running main action method: download_and_extract 15:40:52 INFO - mkdir: /builds/slave/test/build/tests 15:40: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')]} 15:40:52 INFO - https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:40:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:52 INFO - Downloaded 1199 bytes. 15:40:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:52 INFO - Using the following test package requirements: 15:40:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:40:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:40:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:40:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'jsshell-mac.zip'], 15:40:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:40:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:40:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:40:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:40:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:40:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:40:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:40: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')]} 15:40:52 INFO - https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:40:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:53 INFO - Downloaded 18145138 bytes. 15:40:53 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'] 15:40:53 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 15:40:56 INFO - Return code: 0 15:40:56 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 15:40:56 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')]} 15:40:56 INFO - https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:40:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:40:58 INFO - Downloaded 18145138 bytes. 15:40:58 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'] 15:40:58 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 15:41:01 INFO - Return code: 0 15:41: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')]} 15:41:01 INFO - https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:41:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:41:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:41:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:41:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:41:02 INFO - Downloaded 10840152 bytes. 15:41:02 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'] 15:41:02 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 15:41:03 INFO - Return code: 0 15:41:03 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')]} 15:41:03 INFO - https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:41:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:41:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:41:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:41:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b3d6LpclQpmeQPZQ1-LdqA/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 15:41:06 INFO - Downloaded 110453396 bytes. 15:41:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:41:06 INFO - mkdir: /builds/slave/test/properties 15:41:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:41:06 INFO - Writing to file /builds/slave/test/properties/build_url 15:41:06 INFO - Contents: 15:41:06 INFO - build_url:https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:41:07 INFO - Running post-action listener: _resource_record_post_action 15:41:07 INFO - Running post-action listener: set_extra_try_arguments 15:41:07 INFO - ##### 15:41:07 INFO - ##### Finished download-and-extract step (success) 15:41:07 INFO - ##### 15:41:07 INFO - ##### 15:41:07 INFO - ##### Running populate-webroot step. 15:41:07 INFO - ##### 15:41:07 INFO - Running pre-action listener: _resource_record_pre_action 15:41:07 INFO - Running main action method: populate_webroot 15:41:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:41:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:41:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:41:07 INFO - '--tppagecycles', 15:41:07 INFO - '7'], 15:41:07 INFO - 'tests': ['tsvgm']}}, 15:41:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:41:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:41:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:41:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:41:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['damp', 'tps']}, 15:41:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'tests': ['damp', 'tps']}, 15:41:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['dromaeo_dom']}, 15:41:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:41:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'other-e10s': {'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['a11yr', 15:41:07 INFO - 'ts_paint', 15:41:07 INFO - 'tpaint', 15:41:07 INFO - 'sessionrestore', 15:41:07 INFO - 'sessionrestore_no_auto_restore', 15:41:07 INFO - 'tabpaint']}, 15:41:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['tsvgx', 15:41:07 INFO - 'tsvgr_opacity', 15:41:07 INFO - 'tart', 15:41:07 INFO - 'tscrollx', 15:41:07 INFO - 'cart']}, 15:41:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:41:07 INFO - 'tsvgr_opacity', 15:41:07 INFO - 'tart', 15:41:07 INFO - 'tscrollx', 15:41:07 INFO - 'cart']}, 15:41:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'talos_options': ['--disable-e10s'], 15:41:07 INFO - 'tests': ['tp5o']}, 15:41:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'tests': ['tp5o']}, 15:41:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'talos_options': ['--disable-e10s', 15:41:07 INFO - '--xperf_path', 15:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:07 INFO - 'tests': ['tp5n']}, 15:41:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:07 INFO - 'talos_options': ['--xperf_path', 15:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:07 INFO - 'tests': ['tp5n']}}, 15:41:07 INFO - 'talos.zip': {'path': '', 15:41:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:41:07 INFO - Running post-action listener: _resource_record_post_action 15:41:07 INFO - ##### 15:41:07 INFO - ##### Finished populate-webroot step (success) 15:41:07 INFO - ##### 15:41:07 INFO - ##### 15:41:07 INFO - ##### Running create-virtualenv step. 15:41:07 INFO - ##### 15:41:07 INFO - Running pre-action listener: _resource_record_pre_action 15:41:07 INFO - Running main action method: create_virtualenv 15:41:07 INFO - Creating virtualenv /builds/slave/test/build/venv 15:41:07 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 15:41:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:41:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:41:07 INFO - Using real prefix '/tools/python27' 15:41:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:41:08 INFO - Installing distribute.............................................................................................................................................................................................done. 15:41:11 INFO - Installing pip.................done. 15:41:11 INFO - Return code: 0 15:41:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:41:11 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')]} 15:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:11 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')]} 15:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:11 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:11 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 15:41:11 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 15:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:11 INFO - 'HOME': '/Users/cltbld', 15:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:11 INFO - 'LOGNAME': 'cltbld', 15:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:11 INFO - 'MOZ_NO_REMOTE': '1', 15:41:11 INFO - 'NO_EM_RESTART': '1', 15:41:11 INFO - 'PAGER': '/bin/cat', 15:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:11 INFO - 'PWD': '/builds/slave/test', 15:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:11 INFO - 'SHELL': '/bin/bash', 15:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:11 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:11 INFO - 'USER': 'cltbld', 15:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:11 INFO - 'XPC_FLAGS': '0x0', 15:41:11 INFO - 'XPC_SERVICE_NAME': '0', 15:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:11 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 15:41:11 INFO - Cleaning up... 15:41:11 INFO - Return code: 0 15:41:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:41:11 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')]} 15:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:11 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')]} 15:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:11 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:11 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 15:41:11 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 15:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:11 INFO - 'HOME': '/Users/cltbld', 15:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:11 INFO - 'LOGNAME': 'cltbld', 15:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:11 INFO - 'MOZ_NO_REMOTE': '1', 15:41:11 INFO - 'NO_EM_RESTART': '1', 15:41:11 INFO - 'PAGER': '/bin/cat', 15:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:11 INFO - 'PWD': '/builds/slave/test', 15:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:11 INFO - 'SHELL': '/bin/bash', 15:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:11 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:11 INFO - 'USER': 'cltbld', 15:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:11 INFO - 'XPC_FLAGS': '0x0', 15:41:11 INFO - 'XPC_SERVICE_NAME': '0', 15:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:11 INFO - Downloading/unpacking psutil>=0.7.1 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:41:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:14 INFO - Installing collected packages: psutil 15:41:14 INFO - Running setup.py install for psutil 15:41:14 INFO - building 'psutil._psutil_osx' extension 15:41:14 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 15:41:15 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 15:41:15 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 15:41:15 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 15:41:15 INFO - building 'psutil._psutil_posix' extension 15:41:15 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 15:41:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:41:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:41:15 INFO - ^ 15:41:15 INFO - 1 warning generated. 15:41:15 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 15:41:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:16 INFO - Successfully installed psutil 15:41:16 INFO - Cleaning up... 15:41:16 INFO - Return code: 0 15:41:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:41: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')]} 15:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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')]} 15:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:16 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:16 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 15:41:16 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 15:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:16 INFO - 'HOME': '/Users/cltbld', 15:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:16 INFO - 'LOGNAME': 'cltbld', 15:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:16 INFO - 'MOZ_NO_REMOTE': '1', 15:41:16 INFO - 'NO_EM_RESTART': '1', 15:41:16 INFO - 'PAGER': '/bin/cat', 15:41:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:16 INFO - 'PWD': '/builds/slave/test', 15:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:16 INFO - 'SHELL': '/bin/bash', 15:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:16 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:16 INFO - 'USER': 'cltbld', 15:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:16 INFO - 'XPC_FLAGS': '0x0', 15:41:16 INFO - 'XPC_SERVICE_NAME': '0', 15:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:41:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:41:18 INFO - Installing collected packages: mozsystemmonitor 15:41:18 INFO - Running setup.py install for mozsystemmonitor 15:41:18 INFO - Successfully installed mozsystemmonitor 15:41:18 INFO - Cleaning up... 15:41:19 INFO - Return code: 0 15:41:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:19 INFO - 'HOME': '/Users/cltbld', 15:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:19 INFO - 'LOGNAME': 'cltbld', 15:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:19 INFO - 'MOZ_NO_REMOTE': '1', 15:41:19 INFO - 'NO_EM_RESTART': '1', 15:41:19 INFO - 'PAGER': '/bin/cat', 15:41:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:19 INFO - 'PWD': '/builds/slave/test', 15:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:19 INFO - 'SHELL': '/bin/bash', 15:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:19 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:19 INFO - 'USER': 'cltbld', 15:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:19 INFO - 'XPC_FLAGS': '0x0', 15:41:19 INFO - 'XPC_SERVICE_NAME': '0', 15:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:19 INFO - Downloading/unpacking blobuploader==1.2.4 15:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:21 INFO - Downloading blobuploader-1.2.4.tar.gz 15:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:41:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:41:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:22 INFO - Downloading docopt-0.6.1.tar.gz 15:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:41:22 INFO - Installing collected packages: blobuploader, requests, docopt 15:41:22 INFO - Running setup.py install for blobuploader 15:41:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:41:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:41:23 INFO - Running setup.py install for requests 15:41:23 INFO - Running setup.py install for docopt 15:41:23 INFO - Successfully installed blobuploader requests docopt 15:41:23 INFO - Cleaning up... 15:41:23 INFO - Return code: 0 15:41:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:23 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')]} 15:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:23 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')]} 15:41:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:23 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:23 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 15:41:23 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 15:41:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:23 INFO - 'HOME': '/Users/cltbld', 15:41:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:23 INFO - 'LOGNAME': 'cltbld', 15:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:23 INFO - 'MOZ_NO_REMOTE': '1', 15:41:23 INFO - 'NO_EM_RESTART': '1', 15:41:23 INFO - 'PAGER': '/bin/cat', 15:41:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:23 INFO - 'PWD': '/builds/slave/test', 15:41:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:23 INFO - 'SHELL': '/bin/bash', 15:41:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:23 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:23 INFO - 'USER': 'cltbld', 15:41:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:23 INFO - 'XPC_FLAGS': '0x0', 15:41:23 INFO - 'XPC_SERVICE_NAME': '0', 15:41:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-lMtM12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-xCVp5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-IBwhTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-Q0F2Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-6DOCE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-93iF4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-I6zh8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-YBXgtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-TP5ucG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:24 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-KUJ_Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-SAeA2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-JfTVjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-t115xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-VvIpjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-ibM4xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-7Gx2BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:25 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-S5pAgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:41:25 INFO - Running setup.py install for manifestparser 15:41:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:41:25 INFO - Running setup.py install for mozcrash 15:41:26 INFO - Running setup.py install for mozdebug 15:41:26 INFO - Running setup.py install for mozdevice 15:41:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Running setup.py install for mozfile 15:41:26 INFO - Running setup.py install for mozhttpd 15:41:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Running setup.py install for mozinfo 15:41:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Running setup.py install for mozInstall 15:41:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:41:26 INFO - Running setup.py install for mozleak 15:41:27 INFO - Running setup.py install for mozlog 15:41:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Running setup.py install for moznetwork 15:41:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Running setup.py install for mozprocess 15:41:27 INFO - Running setup.py install for mozprofile 15:41:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Running setup.py install for mozrunner 15:41:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:41:27 INFO - Running setup.py install for mozscreenshot 15:41:28 INFO - Running setup.py install for moztest 15:41:28 INFO - Running setup.py install for mozversion 15:41:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:41:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:41:28 INFO - Cleaning up... 15:41:28 INFO - Return code: 0 15:41:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:28 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:28 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 15:41:28 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 15:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:28 INFO - 'HOME': '/Users/cltbld', 15:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:28 INFO - 'LOGNAME': 'cltbld', 15:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:28 INFO - 'MOZ_NO_REMOTE': '1', 15:41:28 INFO - 'NO_EM_RESTART': '1', 15:41:28 INFO - 'PAGER': '/bin/cat', 15:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:28 INFO - 'PWD': '/builds/slave/test', 15:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:28 INFO - 'SHELL': '/bin/bash', 15:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:28 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:28 INFO - 'USER': 'cltbld', 15:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:28 INFO - 'XPC_FLAGS': '0x0', 15:41:28 INFO - 'XPC_SERVICE_NAME': '0', 15:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:28 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-5qKuFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41:28 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)) 15:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:28 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-oQHBgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:28 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)) 15:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:28 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-ZeHcsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:28 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)) 15:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:28 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-_GWVpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-EKVysT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-gWlNKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-mu0Cat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-ShbD2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-wppChW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-FcQrMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-ARQZtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-u2kOND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:29 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)) 15:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:29 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-8gWqaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:30 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)) 15:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:30 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-rUH6h3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41:30 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)) 15:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:30 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-XpTfMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:30 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)) 15:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:30 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-7r621V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41:30 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)) 15:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:30 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-WDlACx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41:30 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)) 15:41:30 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)) 15:41:30 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)) 15:41:30 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)) 15:41:30 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)) 15:41:30 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)) 15:41:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:41:30 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 15:41:30 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 15:41:30 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 15:41:30 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 15:41:30 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 15:41:30 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 15:41:32 INFO - Downloading blessings-1.6.tar.gz 15:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:41:32 INFO - Installing collected packages: blessings 15:41:32 INFO - Running setup.py install for blessings 15:41:33 INFO - Successfully installed blessings 15:41:33 INFO - Cleaning up... 15:41:33 INFO - Return code: 0 15:41:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:33 INFO - Reading from file tmpfile_stdout 15:41:33 INFO - Current package versions: 15:41:33 INFO - blessings == 1.6 15:41:33 INFO - blobuploader == 1.2.4 15:41:33 INFO - docopt == 0.6.1 15:41:33 INFO - manifestparser == 1.1 15:41:33 INFO - mozInstall == 1.12 15:41:33 INFO - mozcrash == 0.17 15:41:33 INFO - mozdebug == 0.1 15:41:33 INFO - mozdevice == 0.48 15:41:33 INFO - mozfile == 1.2 15:41:33 INFO - mozhttpd == 0.7 15:41:33 INFO - mozinfo == 0.9 15:41:33 INFO - mozleak == 0.1 15:41:33 INFO - mozlog == 3.1 15:41:33 INFO - moznetwork == 0.27 15:41:33 INFO - mozprocess == 0.22 15:41:33 INFO - mozprofile == 0.28 15:41:33 INFO - mozrunner == 6.11 15:41:33 INFO - mozscreenshot == 0.1 15:41:33 INFO - mozsystemmonitor == 0.0 15:41:33 INFO - moztest == 0.7 15:41:33 INFO - mozversion == 1.4 15:41:33 INFO - psutil == 3.1.1 15:41:33 INFO - requests == 1.2.3 15:41:33 INFO - wsgiref == 0.1.2 15:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:33 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')]} 15:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:33 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')]} 15:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:33 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:33 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 15:41:33 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 15:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:33 INFO - 'HOME': '/Users/cltbld', 15:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:33 INFO - 'LOGNAME': 'cltbld', 15:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:33 INFO - 'MOZ_NO_REMOTE': '1', 15:41:33 INFO - 'NO_EM_RESTART': '1', 15:41:33 INFO - 'PAGER': '/bin/cat', 15:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:33 INFO - 'PWD': '/builds/slave/test', 15:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:33 INFO - 'SHELL': '/bin/bash', 15:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:33 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:33 INFO - 'USER': 'cltbld', 15:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:33 INFO - 'XPC_FLAGS': '0x0', 15:41:33 INFO - 'XPC_SERVICE_NAME': '0', 15:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 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)) 15:41:33 INFO - Cleaning up... 15:41:33 INFO - Return code: 0 15:41:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:41:33 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')]} 15:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:33 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')]} 15:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:33 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 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:33 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 15:41:33 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 15:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:33 INFO - 'HOME': '/Users/cltbld', 15:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:33 INFO - 'LOGNAME': 'cltbld', 15:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:33 INFO - 'MOZ_NO_REMOTE': '1', 15:41:33 INFO - 'NO_EM_RESTART': '1', 15:41:33 INFO - 'PAGER': '/bin/cat', 15:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:33 INFO - 'PWD': '/builds/slave/test', 15:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:33 INFO - 'SHELL': '/bin/bash', 15:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:33 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:33 INFO - 'USER': 'cltbld', 15:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:33 INFO - 'XPC_FLAGS': '0x0', 15:41:33 INFO - 'XPC_SERVICE_NAME': '0', 15:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:33 INFO - Downloading/unpacking jsonschema 15:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:41:36 INFO - Downloading/unpacking functools32 (from jsonschema) 15:41:36 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 15:41:36 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 15:41:36 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 15:41:36 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 15:41:36 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 15:41:36 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 15:41:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:41:36 INFO - warning: no files found matching '*.txt' 15:41:36 INFO - no previously-included directories found matching 'build' 15:41:36 INFO - no previously-included directories found matching 'dist' 15:41:36 INFO - no previously-included directories found matching '.git*' 15:41:36 INFO - Installing collected packages: jsonschema, functools32 15:41:36 INFO - Running setup.py install for functools32 15:41:37 INFO - warning: no files found matching '*.txt' 15:41:37 INFO - no previously-included directories found matching 'build' 15:41:37 INFO - no previously-included directories found matching 'dist' 15:41:37 INFO - no previously-included directories found matching '.git*' 15:41:37 INFO - Successfully installed jsonschema functools32 15:41:37 INFO - Cleaning up... 15:41:37 INFO - Return code: 0 15:41:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50acc41c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, '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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 15:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:37 INFO - 'HOME': '/Users/cltbld', 15:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:37 INFO - 'LOGNAME': 'cltbld', 15:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:37 INFO - 'MOZ_NO_REMOTE': '1', 15:41:37 INFO - 'NO_EM_RESTART': '1', 15:41:37 INFO - 'PAGER': '/bin/cat', 15:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:37 INFO - 'PWD': '/builds/slave/test', 15:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:37 INFO - 'SHELL': '/bin/bash', 15:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:41:37 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:37 INFO - 'USER': 'cltbld', 15:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:37 INFO - 'XPC_FLAGS': '0x0', 15:41:37 INFO - 'XPC_SERVICE_NAME': '0', 15:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:37 INFO - Downloading/unpacking flake8 15:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:41:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:41:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 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 15:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:41:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:41 INFO - no previously-included directories found matching 'docs/_build' 15:41:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:41:41 INFO - Running setup.py install for pep8 15:41:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:41 INFO - no previously-included directories found matching 'docs/_build' 15:41:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:41:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:41:41 INFO - Cleaning up... 15:41:41 INFO - Return code: 0 15:41:41 INFO - Running post-action listener: _resource_record_post_action 15:41:41 INFO - Running post-action listener: _start_resource_monitoring 15:41:41 INFO - Starting resource monitoring. 15:41:41 INFO - ##### 15:41:41 INFO - ##### Finished create-virtualenv step (success) 15:41:41 INFO - ##### 15:41:41 INFO - ##### 15:41:41 INFO - ##### Running install step. 15:41:41 INFO - ##### 15:41:41 INFO - Running pre-action listener: _resource_record_pre_action 15:41:41 INFO - Running main action method: install 15:41:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:41 INFO - Reading from file tmpfile_stdout 15:41:41 INFO - Detecting whether we're running mozinstall >=1.0... 15:41:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:41:41 INFO - Reading from file tmpfile_stdout 15:41:41 INFO - Output received: 15:41:41 INFO - Usage: mozinstall [options] installer 15:41:41 INFO - Options: 15:41:41 INFO - -h, --help show this help message and exit 15:41:41 INFO - -d DEST, --destination=DEST 15:41:41 INFO - Directory to install application into. [default: 15:41:41 INFO - "/builds/slave/test"] 15:41:41 INFO - --app=APP Application being installed. [default: firefox] 15:41:41 INFO - mkdir: /builds/slave/test/build/application 15:41:41 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'] 15:41:41 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 15:42:14 INFO - Reading from file tmpfile_stdout 15:42:14 INFO - Output received: 15:42:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:42:14 INFO - Running post-action listener: _resource_record_post_action 15:42:14 INFO - ##### 15:42:14 INFO - ##### Finished install step (success) 15:42:14 INFO - ##### 15:42:14 INFO - ##### 15:42:14 INFO - ##### Running run-tests step. 15:42:14 INFO - ##### 15:42:14 INFO - Running pre-action listener: _resource_record_pre_action 15:42:14 INFO - Running main action method: run_tests 15:42:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:42:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:42:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:42:14 INFO - 2016-05-06 15:42:14.819 screenresolution[1636:7400] starting screenresolution argv=screenresolution get 15:42:14 INFO - 2016-05-06 15:42:14.822 screenresolution[1636:7400] Display 0: 1600x1200x32@60 15:42:14 INFO - 2016-05-06 15:42:14.828 screenresolution[1637:7402] starting screenresolution argv=screenresolution list 15:42:14 INFO - Available Modes on Display 0 15:42:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:42:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:42:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:42:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:42:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:42:15 INFO - Intel Iris: 15:42:15 INFO - Chipset Model: Intel Iris 15:42:15 INFO - Type: GPU 15:42:15 INFO - Bus: Built-In 15:42:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:42:15 INFO - Vendor: Intel (0x8086) 15:42:15 INFO - Device ID: 0x0a2e 15:42:15 INFO - Revision ID: 0x0009 15:42:15 INFO - Displays: 15:42:15 INFO - Display: 15:42:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:42:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:42:15 INFO - Display Serial Number: 1600x1200 60 15:42:15 INFO - Main Display: Yes 15:42:15 INFO - Mirror: Off 15:42:15 INFO - Online: Yes 15:42:15 INFO - Rotation: Supported 15:42:15 INFO - Return code: 0 15:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:42:15 INFO - Python 2.7.3 15:42:15 INFO - Return code: 0 15:42:15 INFO - grabbing minidump binary from tooltool 15:42:15 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')]} 15:42:15 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c547e00>, '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 0x10c3b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c542b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:42:15 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 15:42:15 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 15:42:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:42:15 INFO - Return code: 0 15:42:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:42:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:42:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:42:16 INFO - Return code: 0 15:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 15:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0182 --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 15:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YQIWVh1X7Q/Render', 15:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:16 INFO - 'HOME': '/Users/cltbld', 15:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:16 INFO - 'LOGNAME': 'cltbld', 15:42:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:42:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:16 INFO - 'MOZ_NO_REMOTE': '1', 15:42:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:42:16 INFO - 'NO_EM_RESTART': '1', 15:42:16 INFO - 'PAGER': '/bin/cat', 15:42:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:16 INFO - 'PWD': '/builds/slave/test', 15:42:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:16 INFO - 'SHELL': '/bin/bash', 15:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApKvxWwMkw/Listeners', 15:42:16 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 15:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:16 INFO - 'USER': 'cltbld', 15:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:16 INFO - 'XPC_FLAGS': '0x0', 15:42:16 INFO - 'XPC_SERVICE_NAME': '0', 15:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 15:42:16 INFO - mozversion application_buildid: 20160506124403 15:42:16 INFO - mozversion application_changeset: 1d32762a203507d4f0e7743b8a329ab2bbba9799 15:42:16 INFO - mozversion application_display_name: Nightly 15:42:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:42:16 INFO - mozversion application_name: Firefox 15:42:16 INFO - mozversion application_remotingname: firefox 15:42:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:42:16 INFO - mozversion application_vendor: Mozilla 15:42:16 INFO - mozversion application_version: 49.0a1 15:42:16 INFO - mozversion platform_buildid: 20160506124403 15:42:16 INFO - mozversion platform_changeset: 1d32762a203507d4f0e7743b8a329ab2bbba9799 15:42:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:42:16 INFO - mozversion platform_version: 49.0a1 15:42:16 INFO - using testdate: 1462574536 15:42:16 INFO - actual date: 1462574536 15:42:16 INFO - starting webserver on 'localhost:49271' 15:42:16 INFO - SUITE-START | Running 2 tests 15:42:16 INFO - TEST-START | dromaeo_css 15:42:16 INFO - operating with platform_type : mac_ 15:42:16 INFO - Initialising browser for dromaeo_css test... 15:42:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpBzL5JJ/profile http://localhost:49271/getInfo.html) 15:42:19 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 15:42:19 INFO - PROCESS | 1655 | colorDepth:24 15:42:19 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 15:42:19 INFO - PROCESS | 1655 | __metrics 15:42:19 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 15:42:20 INFO - TEST-INFO | 1655: exit 0 15:42:20 INFO - Browser initialized. 15:42:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:42:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpBzL5JJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:32 INFO - PROCESS | 1658 | RSS: Main: 250236928 15:42:32 INFO - PROCESS | 1658 | 15:43:21 INFO - PROCESS | 1658 | 1462574601895 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] 15:45:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:45:34 INFO - PROCESS | 1658 | RSS: Main: 262799360 15:45:34 INFO - PROCESS | 1658 | 15:48:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:48:37 INFO - PROCESS | 1658 | RSS: Main: 274329600 15:48:37 INFO - PROCESS | 1658 | 15:51:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:51:39 INFO - PROCESS | 1658 | RSS: Main: 275996672 15:51:39 INFO - PROCESS | 1658 | 15:54:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:54:41 INFO - PROCESS | 1658 | RSS: Main: 277655552 15:54:41 INFO - PROCESS | 1658 | 15:57:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:57:45 INFO - PROCESS | 1658 | RSS: Main: 280662016 15:57:45 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 16:00:48 INFO - PROCESS | 1658 | RSS: Main: 282132480 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | __start_tp_report 16:00:48 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 16:00:48 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 16:00:48 INFO - PROCESS | 1658 | |i|pagename|runs| 16:00:48 INFO - PROCESS | 1658 | |0;dojo.html;3511.6137224905256;3515;3543.8582456701733;3557.039599308187;3570.1610121621425;5769.682667453289;5870.823875283738;5966.13491043799;5973.402659734024;5980.581359304851;5321;5813.505852002585;5897.4102589741115;5908.995470769969;5968.045112781945;5238.895222095565;5282.181261904411;5351.170568561879;5358.946410535884;5391.32608423947;506.6749102740341;5252.8424147275455;5317.601179911503;5318.202269659562;5330.813421530247;5309.628326017161;5310.150375939831;5317.175837745156;5352.625316227865;5372.7313634318125;5358.624896257264;5359.678419294816;5388.084025715638;5392.91910621341;5396.622236443451;5216.087184742655;5221.451747566527;5291.047611429941;5303.178007408857;5330.093884039684;5659.688717120557;5820.679862607555;5866.76532938682;5887.293524777011;5904.1734157218025;6159.753609855646;6169.321374648785;6230.937690623126;6237.438630523275;6237.563370564062;6177.351378105324;6228.003519436868;6228.470580000705;6300.621962682208;6309.87380252397;6060.666663333515;6065.3934606539;6070.757169713206;6077.878442431127;6113.694315284218;6178.567500274938;6272.2159730033745;6409.839754006094;6421.839454013687;6427.55007149495;4837.637177211724;5310.654807437504;6068.696565171812;6173.938260617426;6241.157443745127;6232.25212974446;6256.5307601930035;6323.7786677466065;6325.462335701425;6332.68336583169;4692.976535117303;6145.740123274718;6148.938510614926;6216.968915155486;6232.18981532407;6176.413240742122;6215.316315205324;6221;6272.2159730033745;6318.557700960889;4056.452378928807;4522.005158865044;5038.748062596929;5040.193569028937;5047.545720885138;4361.869143925687;4498.6626003049905;4515.841945531891;4541.886452838639;4597.011642496813;5814.883702325929;5911.645301281937;5937.109433584997;6041.788537401258;6053.576249662569;6274.498040156776;6311.33730958252;6335.524835637346;6336.778212762531;6341.175647165839;5899.292084949833;6028.57799953999;6047.788327408608;6059.030555111161;6100.603460775035;4959.082569724613;5153.458886816905;5493.917591236134;5495.807646732345;5533.861653458615;6499.54503184782;6521.673916304071;6533.640649764214;6547.443467305237;6568.901466478007;6148.385161483816;6205.503559715122;6243.719032643451;6247.656378899204;6325.7153428094925;5929.792457263975;6280.309165991828;6552.868942621216;6566.868662626625;6569.605823650596;5671.631343962629;5819.476247137609;6146.924288249628;6202.34875338101;6268.247810262797;6172.753089876534;6200.3799620037635;6235.127082208585;6294.968525157345;6322.620642761449;6206.751729930752;6236.906446403398;6242.5942313749465;6243.531735119794;6287.874242515032;6112.5946701277735;6134;6159.23009623797;6222.191115155001;6263.96868015657;6057.515398768176;6093.177421048102;6127.816165515042;6140.749659041634;6165.290991536031;5346.492083252085;6050.515958723204;6210.937890621217;6217.4715149212825;6237.906431403442;5087.694738315713;5971.790987315423;6105.399096011559;6152.907706384497;6167.444929956326 16:00:48 INFO - PROCESS | 1658 | |1;ext.html;516.5067360670558;548.2406866489911;553.4382601659316;577.8587290102049;579.32219303415;38123.80938095309;38601.03497412566;39865.80067099661;39883.80058099713;39948.800255998714;40374.59625403745;41113.58886411135;41149;41161.970950726245;41205.79397103018;8638.913610863905;9397.201237894787;11841.703957401047;12109.939450302736;12121.212121212093;11767.823482647767;11816.231944923595;11827.34949577773;11873.050155987543;11950.641480755548;10103.292769506137;11809.763804723943;11880;11915.28508289501;11954.222975506607;11687.941560292229;11804;11808;11885.34630595317;11897.048236141089;8691.608877600524;11655.708607284801;11687.824682629682;11693.649190524298;12024.759504809856;42603.9349016274;42888.57111428877;43406;43458.913527161756;43726.562734372565;3205.5512228288057;3245.7565682573663;3350.31318579693;3392.270661807723;3652.3060618482405;29833.165849244288;30377.848110759525;30553.694463055308;30612.693873061206;30835.537466938014;2755.2698534888273;2806.9438611227865;2821.8306901585765;2925.3417980954114;3013.9397212055637;465559.6722016402;535106;535141.3242933799;538273;539593;66514.66742666256;66784;67202.32797672058;71572.64213678899;71897;69689.65155174192;74866.8769968451;74869.87695184577;74896.62551687207;75159.62420187864;15809.920950395175;17508.38720644797;17675.646487070444;17868.821311786975;17921.910390447963;13158;13302.334883255991;13483.460661573623;13563.389647465889;13862.584122476343;20518;21181;21206.893965530384;21261.36215913525;21321.68017479739;7118.857622847618;7228.783136505913;7726.4591478597085;8014.479058861272;8418.789530261793;784.5136015670207;1001.9899801001897;1047.1361127070045;1087.2660953856116;1107.9806577948307;29654.14496115491;30022.84988575043;30089.699103009127;30367.544486832714;31126;1075.763332066944;1117.4133579870634;1130.3218069158438;1175.0540814644123;1222.4254600337827;15517.068975861484;15601.375944962074;16050;16073.035617862966;16284.185790710417;660433;664863;674371;675084;677629;62261.377386226464;62516;62696.05955910665;63199.736005279636;64034.6798266015;1454.3964254834336;1470.0958910270108;1476.763717805146;1491.0755331694636;1497.3636204612997;30270.697293027668;30476.238094046937;30523.38953220879;30578;30613;51767.741161293954;51865.22202166895;52520.737396312776;52947.73526132345;53552.732236340125;525814;528642;531803.3409832926;531909;532718;26121.869390652926;44904.77547612241;45836.74856560186;45959.851003725176;47004.52995470002;48272.75863620659;48291.27563086486;48965.755171223915;50729.23906141337;50819.491805081474;34569.308613828085;35615.10962225965;38918.80540597279;40768.79615601903;43073.78463107665;29467.705322947353;38999.80500097481;40351.1929761409;40761.79619101885;40876.56932007366;38742;39042.2191556173;39529.407058893565;39871.2025759489;40192.59807401888;34095.31809363749;36069.63930360663;36828.447573285885;37068.25863482769;37256.62743372531;22229.888850555642;23677;23780.76219237855;25750;25897.870510647324 16:00:48 INFO - PROCESS | 1658 | |2;jquery.html;391.4206973678957;394.1781385810585;397.9263836190304;398.16186926519674;418.87224396559054;20508.48728781781;20856.582868342623;21898.671519927175;21918.342449726493;21983.780162198374;11768.529258829636;12153.635390938263;12382.876171238286;12410.503579856817;12497.125201235916;5179.948200517984;5337.2794672719265;5537.0863807471715;5557.499825015748;5564.304461942257;6100.664463454508;6164.10620460033;6192.690365481731;6201.16284301618;6221.222347206599;5845.8830823383505;6138.14066030756;6143;6198;6218.626882387071;6078.939210607881;6118.204633397652;6121.326654068072;6139.6009259398;6161.045038019114;5745.54035677149;6005.159277701126;6033.396660333975;6042.093685947099;6055.182550355689;13550.796738048937;13939.860601393957;14092;14111.576652700436;14144.151350918977;1964.7052942058663;2070.89645517725;2073.232903825579;2100.831933445321;2125.7661657217695;8132.146124656944;8269.793255168608;9625.374350667184;9877.555510002067;9898.024887318285;1729.2564197395116;1800.7028840241344;1801.5946412057313;1815.509812350658;1827.269092363052;127327.72672273344;132743;132986;133659.66340336666;135852;9544.61821527145;11618.360990145624;11821.822672659917;11878.524859005716;11881.821772673416;15216.619584510476;15415.229238538028;15424.305906234247;15448.691026179638;15456.613584660474;8744.650213991368;8809.691660791841;8811.206991370807;8812.8678069829;8840.071792461698;8620.094890036456;8682.956585217033;8691.391602587759;8807.251383632463;8852.114788521096;4430.379746835446;4679.3916790816975;4715.4577223619035;4737.076270127361;4796.640251981115;1667.7081510735572;1859.9535011624819;2024.2813801100692;2027.260050081739;2033.013988215714;877.9253763430053;949.8290307744534;958.2190514730472;996.6594929819381;1102.32207192576;20541.178352865718;20766.896165519378;20816.47958801042;21130.47173820636;21137.471563211042;935.1723724503863;963.2246041936169;968.4625033106553;981.2248323824242;994.1251698505414;10706.250562460553;10997.450127493594;11001.889981100247;11011.504482298316;11068.225224234226;565683.1715841395;611473;611889;628329;632815;110875;129412;137300.25399492154;141493;144505;977.154761131616;1024.774549599087;1027.635189507729;1050.4642632257603;1062.4528367890389;18336;18585.88484690923;18616.348427804962;18695.532611684815;18783.906080470057;49619.50380496149;52532;55322.72338638281;55567;55933.16100258581;80719;81029;81374.18625813903;81435;83731;7018.613976231356;7469.88795168062;7516.924830751841;7746.845063098819;8648.221660050625;9202.861957070783;11778;12176.330301833485;12515.37423128858;12807.871921280668;9986.101250887456;11046.005859472685;11203.663890083311;11343.071677814627;11538.019268361948;10878.891211087788;10892.128629709447;10912.072473839813;11169.38568378747;11393.715157120807;9760.316777825485;10832.97086776755;11399.430028498376;11468.655940321803;11735.882641173821;7451.627418628938;7574.848503030019;7660.693572257047;7738.800485924466;7776.805579860549;9962;10609.522571484147;11194.552217911192;11426.600068997544;11660.30038197711 16:00:48 INFO - PROCESS | 1658 | |3;mootools.html;1616.25652199988;1644.55596988813;1916.9233230670766;1936.3900371383022;1971.921123155073;7981.800454988614;8155.714549990752;8591.312694984403;9093.408928419649;9120.863187052191;8651.221390074892;8741.344399170055;8867.024627290994;8870.689525866595;8890.333225008135;2150.720321408767;2437.695288088989;2461.323136137559;2469.6912885889265;2478.2565230430887;1990.3033938121687;2087.6242276390244;2330.8484948478294;2464.039024780337;2487.253823852846;2440.2069327468644;2443.328084776692;2452.3746444656585;2464.691913510811;2468.06213638817;2067.3177851309033;2434.8904299306487;2437.5124975004983;2445.9143929962543;2455.4966231922413;2090.979090209109;2404.2787163850776;2412.5657381671294;2426.551088048717;2448.7428819973825;7797.415193860426;8803.691870784556;8899.777505562348;8930.240929520998;9204.263658907339;5314.229436731653;5472.945270547283;5502.779888804443;5505.229267902497;5510.090835012258;3612.313660404515;3630.364686179908;3661.9633803662155;3707.258548290353;3708.072981754561;1042.5308611125024;1047.9109275711573;1063.2769716592713;1065.3288428290127;1066.3122286125483;11683.357415342152;11802.881971180264;11807.822882656767;11817.113716471213;11849.111316651286;4774.570288674036;4847.2729090636685;4949.925751113737;4977.104121258136;4992.126377883928;9858.753531161636;9861.211103111735;9983.850242246372;10028.448435336126;10093.899061009295;5415.214793854869;5423.105187643994;5482.122860342326;5497.36780270274;5505.587080968943;4456.910861782746;4850.272459131088;5009.7244651544515;5029.497050295015;5036.5718913892;4051.825218817059;4069.8372065117655;4112.773797441109;4133.359329303963;4135.421041054255;1360.7142500074897;1375.3948262764352;1376.2980879751199;1376.9586912392645;1386.473140206715;917.2111983694018;944.15498129174;947.7962238118838;951.4624237306039;988.8269552828226;5246.664875996027;5993.640381577119;6077.453029227391;6100.48145907594;6112.869119212871;423.60393032514696;456.33375272103103;469.7416420968454;475.8453502611665;478.810869706464;3962.6235507627307;3982.9601703983167;4006.5760215069463;4072.1041370898356;4072.552019277877;8976.955115224382;10423.895761042293;10847.891521084846;10908.50911708975;10920.344779313267;10753.193510486744;10761.139108871272;10815.945920270347;10868.45657717111;10888.564457421615;7415.703371865173;8301.837742716025;8342.457740246864;8379.832403351898;8398.118197589287;4049.979750101231;5772.942270577409;5827.1353166986155;5829.59192856496;5919.348871624204;9726;10106.494675266355;10111.443870587189;10128.848067279145;10186.236032297755;9903.405795652285;10291.53688084023;10311.329763565343;10331.380117192992;10383.376997380181;6941.271166527442;7187.9640601796655;7340.486165968331;7566.167721550735;7623.618819059136;8289.171082891662;8325.167483251626;8351.916480835356;8409.705660302105;8494.023187333536;7431.071116110486;7503.399728021855;7564.924350756422;7655.846883062419;7703.229677032252;6676.599404035774;6914.792556223321;7032;7301.817454563678;7343.1555371133;6983.790486285419;7071.752488662872;7110.146782386147;7116.715331386894;7120.465965052538;4680.3915490985955;4687.531246875285;4815.22956326986;4994.9750251248515;5051.065552872583;4908.141075311734;4991.900161996812;5033.219850923113;5096.184610462308;5239.135542635422 16:00:48 INFO - PROCESS | 1658 | |4;prototype.html;316.8637485881071;320.63607805141174;325.2373184881452;326.0755757427691;330.0165506789768;4889.535494128057;4921.237208232729;5167.018266529361;5502.80740174094;5607.747651355683;4820.614350851933;5470.589705772073;5499.477549632778;5502.614816962804;5538.390777014525;3966.781826999521;4091.2635725569385;4169.97915010426;4207.873763787091;4208.579142085798;3490.703290220334;4110.219058378913;4178.958210417887;4182.20538097762;4204.201201771669;3611.494390785292;3800.5059342285604;4157.916841663165;4184.949374806338;4241.384999175118;3637.1452708613456;4097.672186225095;4102.241085399196;4161.542230354658;4194.727342722713;2889.1188187602775;3185.152611485356;3463.4631632097066;3615.9513741015076;3618.4391419330045;4386.758728269944;4692.507286734878;5028.472010438924;5307.442718514539;5366.490183432567;3778.225463779946;4269.978650106729;4668.229742092551;4890.557285600757;4899.383203542822;5141.794328226866;5592.468715472024;5742.7415766290615;5811.709414529299;5819.359870414251;3764.247150569897;3957.742746721454;3973.9602603974167;3988.4216788565786;3991.880243592697;5688.829335119953;5720.370759216441;5761.53907687384;5997;6236.251649802008;3327.800331980089;3673.4673472346362;3975.9463742108364;4017.5580686124504;4037.9097643636055;4091.2635725569685;4100.91798164035;4109.91780164395;4119.958800412018;4120.938185927213;4780.282957556395;4800.66395352329;4823.059503396804;4838.709677419366;4839.491853355417;5230.398504172069;5241.842744717665;5387.868547605047;5393.919091213635;5914.645121292736;1198.4127777389017;1225.9570915017932;1292.8060790881443;1333.8328962158112;1338.9598312050655;837.5835713965254;853.6756032707656;880.9560670605354;901.3104974694364;904.4799240436776;4833;5044.596432285409;5223.921641175385;5231.24146998683;5293.311869456946;5567.331920169606;5615.0454422748235;5736.77052917887;5763.538916886639;5794.507466865363;4300.849470268588;5029.572486338702;5103.413107492612;5212.582993360522;5248.58565060069;2583.147561304765;3019.958114450555;3021.6402618821617;3038.9544156837665;3042.741366983787;5161.638685292069;5205.687658740488;6034.728437220336;6063.757449701962;6095.512359011357;4612;5130.666026833051;5463.623323854056;5733;5955.642661440327;5384.703841288767;5670.603057785916;5747.137919322185;5845.983135818136;5855.268091488564;4899.485554016919;5063.518965698253;5127.282360409305;5475.397706252389;5669.659820410789;4891.217405215149;5155.510226528474;5215.426303106731;5657.519110875622;5732.455416735404;5506.421825708403;5631.535800691768;5634.605577609528;6039.335673075871;6040.6677632730625;3230.53157292196;3263.8041717497026;3790.995386222598;3805.942910856395;3808.3906574947873;5034.748262586929;5737.96302312673;5749.975017487692;5758.62568933018;5780.508656764222;5292.364916210079;5319.361676598833;5527.623122057554;5540.972295138498;5576.275084239185;5091.261767043832;5121.001404726043;5555.722183897646;5570.972145139248;5606;4809.927851082307;4980.850574482772;5405.540529054917;5439.3200849893765;5442.891142177055;5258.737063146904;5859.531237501075;5865.618734782225;5903.321118071305;5909.231799866162;4927.778249978688;5164;5707.714614269187;5764.1842819513395;5785.56608254391 16:00:48 INFO - PROCESS | 1658 | |5;yui.html;655.7278729327329;703.9401650859677;721.7726416178905;751.2112282103786;753.7248904149986;12559.37203139844;14836.554903352902;15091.698166036673;15184.620384490394;15261.923690381533;14093.365798539066;14366.281685915716;14379.92810035951;14404.423823047091;14445.133292002487;724.2901956083042;909.4679612426738;910.2308549275867;932.8973812880578;936.1060187520901;894.9239314658262;926.1201858234671;936.5504557812254;937.3906960475712;938.0853667674031;949.1837020162654;995.9701208963743;995.9741466289734;996.875390576178;1001.2540657210388;939.8825146856643;983.7442265011113;985.8225519406504;986.7286496213562;988.5946761827652;693.743314973458;724.2504008351331;728.2535401213745;730.5105579261855;735.8638651849371;14584.927075364556;14828.555143345717;15043.623909402244;15056.473023444236;15088.849111508855;10589.470526473646;12752.617421477371;12825.166364186298;12933.547325843645;13177.27524986135;9560.187384072362;9580.377275477073;9946;10066.496675166212;10202.9489852551;3124.812511249332;3162.778605497616;3164.8259345735974;3178.1577881861326;3240.983795081033;75868;77676.61161694211;77864.8320275202;84352;85812.28375432457;6913.89629155563;8515.97808263;9485.525723713787;9565.808683826284;9733.977932317146;20410.69383959242;20727.27454539084;21096.47258818511;21117.155313787276;21577.568448631253;5220.530152286236;7301.379382752525;7316;7557.168711441737;7696.384289256846;4162.791860407028;4179.185058913483;4188.937165942513;4206.684498662613;4223.894402639959;3431.9485207721905;3562.4656301554455;3566.928661426757;3569.696575791035;3583.7849729016343;633.3160186998031;640.1997503121098;646.1503123392723;646.5280345347923;649.7238673563717;483.4826735393166;505.23709199108873;540.978360865561;548.6762809942073;576.9048107062372;7592.468527203043;7719.768406947801;7907.169747176578;8054.395920306;8082.515223064496;474.45437746591693;498.77056553985574;514.5677630790154;532.89608526337;575.1487798058896;3090.8454577271045;3770.7171962102952;3774.245150969762;3778.4899038629983;3901.49280593521;86737.56631216929;87106.56446717726;87167.82080448039;87315.56342218249;89088.5545572268;67751.64496710038;74041.62979185177;77931.83102253471;78409.82385264112;78810;69568.30431695719;70446.64776676186;70893;72456;72734;8338.523320063547;8353.916460835557;8429;8487.405881588229;8588.441751286146;42656.78671606622;43093;43096.70709878708;43152.136957261304;43971;55578.72210639083;58306;58344;61483.38516614777;63072.68463657652;10975.945120274348;11067.944660276647;11274.492647830702;11453.484593193492;11584.247023943417;11950.760984780429;12039.75920481603;12089.637310880687;12252.632421027383;12293.815592766294;11356.94321528387;11358.716032099264;11412.942935285602;11793.646190613952;11842.230255033395;11416.597313042366;11557.711057223636;11593.014593759624;11775.352355620524;11995.340256285988;11263.324200547993;11274.267172633752;11336.94331528337;11718.355490447766;11724;7352;9345.53272336394;9398.107179817905;9722.27082968766;10134.543945522617;9843.2125429964;9879.901200987899;9880.45657488845;9927.702168934944;9933.503324833873 16:00:48 INFO - PROCESS | 1658 | __end_tp_report 16:00:48 INFO - PROCESS | 1658 | __start_cc_report 16:00:48 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,414 16:00:48 INFO - PROCESS | 1658 | __end_cc_report 16:00:48 INFO - PROCESS | 1658 | __startTimestamp1462575648411__endTimestamp 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | ------- Summary: start ------- 16:00:48 INFO - PROCESS | 1658 | Number of tests: 6 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5771.90 Median:6068.70 stddev:772.45 (12.7%) stddev-sans-first:754.19 16:00:48 INFO - PROCESS | 1658 | Values: 3511.6 3515.0 3543.9 3557.0 3570.2 5769.7 5870.8 5966.1 5973.4 5980.6 5321.0 5813.5 5897.4 5909.0 5968.0 5238.9 5282.2 5351.2 5358.9 5391.3 506.7 5252.8 5317.6 5318.2 5330.8 5309.6 5310.2 5317.2 5352.6 5372.7 5358.6 5359.7 5388.1 5392.9 5396.6 5216.1 5221.5 5291.0 5303.2 5330.1 5659.7 5820.7 5866.8 5887.3 5904.2 6159.8 6169.3 6230.9 6237.4 6237.6 6177.4 6228.0 6228.5 6300.6 6309.9 6060.7 6065.4 6070.8 6077.9 6113.7 6178.6 6272.2 6409.8 6421.8 6427.6 4837.6 5310.7 6068.7 6173.9 6241.2 6232.3 6256.5 6323.8 6325.5 6332.7 4693.0 6145.7 6148.9 6217.0 6232.2 6176.4 6215.3 6221.0 6272.2 6318.6 4056.5 4522.0 5038.7 5040.2 5047.5 4361.9 4498.7 4515.8 4541.9 4597.0 5814.9 5911.6 5937.1 6041.8 6053.6 6274.5 6311.3 6335.5 6336.8 6341.2 5899.3 6028.6 6047.8 6059.0 6100.6 4959.1 5153.5 5493.9 5495.8 5533.9 6499.5 6521.7 6533.6 6547.4 6568.9 6148.4 6205.5 6243.7 6247.7 6325.7 5929.8 6280.3 6552.9 6566.9 6569.6 5671.6 5819.5 6146.9 6202.3 6268.2 6172.8 6200.4 6235.1 6295.0 6322.6 6206.8 6236.9 6242.6 6243.5 6287.9 6112.6 6134.0 6159.2 6222.2 6264.0 6057.5 6093.2 6127.8 6140.7 6165.3 5346.5 6050.5 6210.9 6217.5 6237.9 5087.7 5971.8 6105.4 6152.9 6167.4 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:72428.58 Median:19860.97 stddev:154387.73 (777.3%) stddev-sans-first:154726.42 16:00:48 INFO - PROCESS | 1658 | Values: 516.5 548.2 553.4 577.9 579.3 38123.8 38601.0 39865.8 39883.8 39948.8 40374.6 41113.6 41149.0 41162.0 41205.8 8638.9 9397.2 11841.7 12109.9 12121.2 11767.8 11816.2 11827.3 11873.1 11950.6 10103.3 11809.8 11880.0 11915.3 11954.2 11687.9 11804.0 11808.0 11885.3 11897.0 8691.6 11655.7 11687.8 11693.6 12024.8 42603.9 42888.6 43406.0 43458.9 43726.6 3205.6 3245.8 3350.3 3392.3 3652.3 29833.2 30377.8 30553.7 30612.7 30835.5 2755.3 2806.9 2821.8 2925.3 3013.9 465559.7 535106.0 535141.3 538273.0 539593.0 66514.7 66784.0 67202.3 71572.6 71897.0 69689.7 74866.9 74869.9 74896.6 75159.6 15809.9 17508.4 17675.6 17868.8 17921.9 13158.0 13302.3 13483.5 13563.4 13862.6 20518.0 21181.0 21206.9 21261.4 21321.7 7118.9 7228.8 7726.5 8014.5 8418.8 784.5 1002.0 1047.1 1087.3 1108.0 29654.1 30022.8 30089.7 30367.5 31126.0 1075.8 1117.4 1130.3 1175.1 1222.4 15517.1 15601.4 16050.0 16073.0 16284.2 660433.0 664863.0 674371.0 675084.0 677629.0 62261.4 62516.0 62696.1 63199.7 64034.7 1454.4 1470.1 1476.8 1491.1 1497.4 30270.7 30476.2 30523.4 30578.0 30613.0 51767.7 51865.2 52520.7 52947.7 53552.7 525814.0 528642.0 531803.3 531909.0 532718.0 26121.9 44904.8 45836.7 45959.9 47004.5 48272.8 48291.3 48965.8 50729.2 50819.5 34569.3 35615.1 38918.8 40768.8 43073.8 29467.7 38999.8 40351.2 40761.8 40876.6 38742.0 39042.2 39529.4 39871.2 40192.6 34095.3 36069.6 36828.4 37068.3 37256.6 22229.9 23677.0 23780.8 25750.0 25897.9 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35471.93 Median:392.80 stddev:102425.75 (26075.8%) stddev-sans-first:102679.21 16:00:48 INFO - PROCESS | 1658 | Values: 391.4 394.2 397.9 398.2 418.9 20508.5 20856.6 21898.7 21918.3 21983.8 11768.5 12153.6 12382.9 12410.5 12497.1 5179.9 5337.3 5537.1 5557.5 5564.3 6100.7 6164.1 6192.7 6201.2 6221.2 5845.9 6138.1 6143.0 6198.0 6218.6 6078.9 6118.2 6121.3 6139.6 6161.0 5745.5 6005.2 6033.4 6042.1 6055.2 13550.8 13939.9 14092.0 14111.6 14144.2 1964.7 2070.9 2073.2 2100.8 2125.8 8132.1 8269.8 9625.4 9877.6 9898.0 1729.3 1800.7 1801.6 1815.5 1827.3 127327.7 132743.0 132986.0 133659.7 135852.0 9544.6 11618.4 11821.8 11878.5 11881.8 15216.6 15415.2 15424.3 15448.7 15456.6 8744.7 8809.7 8811.2 8812.9 8840.1 8620.1 8683.0 8691.4 8807.3 8852.1 4430.4 4679.4 4715.5 4737.1 4796.6 1667.7 1860.0 2024.3 2027.3 2033.0 877.9 949.8 958.2 996.7 1102.3 20541.2 20766.9 20816.5 21130.5 21137.5 935.2 963.2 968.5 981.2 994.1 10706.3 10997.5 11001.9 11011.5 11068.2 565683.2 611473.0 611889.0 628329.0 632815.0 110875.0 129412.0 137300.3 141493.0 144505.0 977.2 1024.8 1027.6 1050.5 1062.5 18336.0 18585.9 18616.3 18695.5 18783.9 49619.5 52532.0 55322.7 55567.0 55933.2 80719.0 81029.0 81374.2 81435.0 83731.0 7018.6 7469.9 7516.9 7746.8 8648.2 9202.9 11778.0 12176.3 12515.4 12807.9 9986.1 11046.0 11203.7 11343.1 11538.0 10878.9 10892.1 10912.1 11169.4 11393.7 9760.3 10833.0 11399.4 11468.7 11735.9 7451.6 7574.8 7660.7 7738.8 7776.8 9962.0 10609.5 11194.6 11426.6 11660.3 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5729.89 Median:4848.77 stddev:3209.55 (66.2%) stddev-sans-first:3203.67 16:00:48 INFO - PROCESS | 1658 | Values: 1616.3 1644.6 1916.9 1936.4 1971.9 7981.8 8155.7 8591.3 9093.4 9120.9 8651.2 8741.3 8867.0 8870.7 8890.3 2150.7 2437.7 2461.3 2469.7 2478.3 1990.3 2087.6 2330.8 2464.0 2487.3 2440.2 2443.3 2452.4 2464.7 2468.1 2067.3 2434.9 2437.5 2445.9 2455.5 2091.0 2404.3 2412.6 2426.6 2448.7 7797.4 8803.7 8899.8 8930.2 9204.3 5314.2 5472.9 5502.8 5505.2 5510.1 3612.3 3630.4 3662.0 3707.3 3708.1 1042.5 1047.9 1063.3 1065.3 1066.3 11683.4 11802.9 11807.8 11817.1 11849.1 4774.6 4847.3 4949.9 4977.1 4992.1 9858.8 9861.2 9983.9 10028.4 10093.9 5415.2 5423.1 5482.1 5497.4 5505.6 4456.9 4850.3 5009.7 5029.5 5036.6 4051.8 4069.8 4112.8 4133.4 4135.4 1360.7 1375.4 1376.3 1377.0 1386.5 917.2 944.2 947.8 951.5 988.8 5246.7 5993.6 6077.5 6100.5 6112.9 423.6 456.3 469.7 475.8 478.8 3962.6 3983.0 4006.6 4072.1 4072.6 8977.0 10423.9 10847.9 10908.5 10920.3 10753.2 10761.1 10815.9 10868.5 10888.6 7415.7 8301.8 8342.5 8379.8 8398.1 4050.0 5772.9 5827.1 5829.6 5919.3 9726.0 10106.5 10111.4 10128.8 10186.2 9903.4 10291.5 10311.3 10331.4 10383.4 6941.3 7188.0 7340.5 7566.2 7623.6 8289.2 8325.2 8351.9 8409.7 8494.0 7431.1 7503.4 7564.9 7655.8 7703.2 6676.6 6914.8 7032.0 7301.8 7343.2 6983.8 7071.8 7110.1 7116.7 7120.5 4680.4 4687.5 4815.2 4995.0 5051.1 4908.1 4991.9 5033.2 5096.2 5239.1 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4556.52 Median:5124.14 stddev:1395.64 (27.2%) stddev-sans-first:1362.80 16:00:48 INFO - PROCESS | 1658 | Values: 316.9 320.6 325.2 326.1 330.0 4889.5 4921.2 5167.0 5502.8 5607.7 4820.6 5470.6 5499.5 5502.6 5538.4 3966.8 4091.3 4170.0 4207.9 4208.6 3490.7 4110.2 4179.0 4182.2 4204.2 3611.5 3800.5 4157.9 4184.9 4241.4 3637.1 4097.7 4102.2 4161.5 4194.7 2889.1 3185.2 3463.5 3616.0 3618.4 4386.8 4692.5 5028.5 5307.4 5366.5 3778.2 4270.0 4668.2 4890.6 4899.4 5141.8 5592.5 5742.7 5811.7 5819.4 3764.2 3957.7 3974.0 3988.4 3991.9 5688.8 5720.4 5761.5 5997.0 6236.3 3327.8 3673.5 3975.9 4017.6 4037.9 4091.3 4100.9 4109.9 4120.0 4120.9 4780.3 4800.7 4823.1 4838.7 4839.5 5230.4 5241.8 5387.9 5393.9 5914.6 1198.4 1226.0 1292.8 1333.8 1339.0 837.6 853.7 881.0 901.3 904.5 4833.0 5044.6 5223.9 5231.2 5293.3 5567.3 5615.0 5736.8 5763.5 5794.5 4300.8 5029.6 5103.4 5212.6 5248.6 2583.1 3020.0 3021.6 3039.0 3042.7 5161.6 5205.7 6034.7 6063.8 6095.5 4612.0 5130.7 5463.6 5733.0 5955.6 5384.7 5670.6 5747.1 5846.0 5855.3 4899.5 5063.5 5127.3 5475.4 5669.7 4891.2 5155.5 5215.4 5657.5 5732.5 5506.4 5631.5 5634.6 6039.3 6040.7 3230.5 3263.8 3791.0 3805.9 3808.4 5034.7 5738.0 5750.0 5758.6 5780.5 5292.4 5319.4 5527.6 5541.0 5576.3 5091.3 5121.0 5555.7 5571.0 5606.0 4809.9 4980.9 5405.5 5439.3 5442.9 5258.7 5859.5 5865.6 5903.3 5909.2 4927.8 5164.0 5707.7 5764.2 5785.6 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:17636.97 Median:29441.45 stddev:24568.58 (83.4%) stddev-sans-first:24604.42 16:00:48 INFO - PROCESS | 1658 | Values: 655.7 703.9 721.8 751.2 753.7 12559.4 14836.6 15091.7 15184.6 15261.9 14093.4 14366.3 14379.9 14404.4 14445.1 724.3 909.5 910.2 932.9 936.1 894.9 926.1 936.6 937.4 938.1 949.2 996.0 996.0 996.9 1001.3 939.9 983.7 985.8 986.7 988.6 693.7 724.3 728.3 730.5 735.9 14584.9 14828.6 15043.6 15056.5 15088.8 10589.5 12752.6 12825.2 12933.5 13177.3 9560.2 9580.4 9946.0 10066.5 10202.9 3124.8 3162.8 3164.8 3178.2 3241.0 75868.0 77676.6 77864.8 84352.0 85812.3 6913.9 8516.0 9485.5 9565.8 9734.0 20410.7 20727.3 21096.5 21117.2 21577.6 5220.5 7301.4 7316.0 7557.2 7696.4 4162.8 4179.2 4188.9 4206.7 4223.9 3431.9 3562.5 3566.9 3569.7 3583.8 633.3 640.2 646.2 646.5 649.7 483.5 505.2 541.0 548.7 576.9 7592.5 7719.8 7907.2 8054.4 8082.5 474.5 498.8 514.6 532.9 575.1 3090.8 3770.7 3774.2 3778.5 3901.5 86737.6 87106.6 87167.8 87315.6 89088.6 67751.6 74041.6 77931.8 78409.8 78810.0 69568.3 70446.6 70893.0 72456.0 72734.0 8338.5 8353.9 8429.0 8487.4 8588.4 42656.8 43093.0 43096.7 43152.1 43971.0 55578.7 58306.0 58344.0 61483.4 63072.7 10975.9 11067.9 11274.5 11453.5 11584.2 11950.8 12039.8 12089.6 12252.6 12293.8 11356.9 11358.7 11412.9 11793.6 11842.2 11416.6 11557.7 11593.0 11775.4 11995.3 11263.3 11274.3 11336.9 11718.4 11724.0 7352.0 9345.5 9398.1 9722.3 10134.5 9843.2 9879.9 9880.5 9927.7 9933.5 16:00:48 INFO - PROCESS | 1658 | -------- Summary: end -------- 16:00:48 INFO - PROCESS | 1658 | 16:00:48 INFO - TEST-INFO | 1658: exit 0 16:00:48 INFO - TEST-OK | dromaeo_css | took 1112488ms 16:00:48 INFO - TEST-START | kraken 16:00:48 INFO - operating with platform_type : mac_ 16:00:48 INFO - Initialising browser for kraken test... 16:00:48 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpufQtYw/profile http://localhost:49271/getInfo.html) 16:00:51 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:00:51 INFO - PROCESS | 1669 | colorDepth:24 16:00:51 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:00:51 INFO - PROCESS | 1669 | __metrics 16:00:52 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 16:00:52 INFO - TEST-INFO | 1669: exit 0 16:00:52 INFO - Browser initialized. 16:00:52 INFO - Running cycle 1/1 for kraken test... 16:00:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpufQtYw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:04 INFO - PROCESS | 1672 | RSS: Main: 250478592 16:01:04 INFO - PROCESS | 1672 | 16:01:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 16:01:08 INFO - PROCESS | 1672 | RSS: Main: 262082560 16:01:08 INFO - PROCESS | 1672 | 16:01:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 16:01:11 INFO - PROCESS | 1672 | RSS: Main: 267350016 16:01:11 INFO - PROCESS | 1672 | 16:01:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 16:01:15 INFO - PROCESS | 1672 | RSS: Main: 267292672 16:01:15 INFO - PROCESS | 1672 | 16:01:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 16:01:18 INFO - PROCESS | 1672 | RSS: Main: 267890688 16:01:18 INFO - PROCESS | 1672 | 16:01:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:21 INFO - PROCESS | 1672 | RSS: Main: 268378112 16:01:21 INFO - PROCESS | 1672 | 16:01:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:24 INFO - PROCESS | 1672 | RSS: Main: 269213696 16:01:24 INFO - PROCESS | 1672 | 16:01:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:27 INFO - PROCESS | 1672 | RSS: Main: 269234176 16:01:27 INFO - PROCESS | 1672 | 16:01:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 16:01:31 INFO - PROCESS | 1672 | RSS: Main: 269557760 16:01:31 INFO - PROCESS | 1672 | 16:01:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:01:33 INFO - PROCESS | 1672 | RSS: Main: 268414976 16:01:33 INFO - PROCESS | 1672 | 16:01:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:01:36 INFO - PROCESS | 1672 | RSS: Main: 268509184 16:01:36 INFO - PROCESS | 1672 | 16:01:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:01:39 INFO - PROCESS | 1672 | RSS: Main: 268734464 16:01:39 INFO - PROCESS | 1672 | 16:01:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:01:42 INFO - PROCESS | 1672 | RSS: Main: 268832768 16:01:42 INFO - PROCESS | 1672 | 16:01:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:01:46 INFO - PROCESS | 1672 | RSS: Main: 268808192 16:01:46 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 16:01:48 INFO - PROCESS | 1672 | RSS: Main: 270585856 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | __start_tp_report 16:01:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 16:01:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 16:01:48 INFO - PROCESS | 1672 | |i|pagename|runs| 16:01:48 INFO - PROCESS | 1672 | |0;ai-astar;111;114;111;116;115;115;115;117;117;117 16:01:48 INFO - PROCESS | 1672 | |1;audio-beat-detection;153;119;147;173;112;142;114;192;187;153 16:01:48 INFO - PROCESS | 1672 | |2;audio-dft;195;198;209;262;267;194;198;200;198;194 16:01:48 INFO - PROCESS | 1672 | |3;audio-fft;66;85;73;141;58;59;58;67;148;73 16:01:48 INFO - PROCESS | 1672 | |4;audio-oscillator;81;97;129;79;81;89;124;83;85;122 16:01:48 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;91;86;87;84;89;84;90;87;87;85 16:01:48 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;96;94;121;94;95;96;96;104;93 16:01:48 INFO - PROCESS | 1672 | |7;imaging-desaturate;138;142;90;119;90;146;132;91;122;150 16:01:48 INFO - PROCESS | 1672 | |8;json-parse-financial;58;60;59;62;61;62;60;79;65;60 16:01:48 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;66;67;67;70;71;72;75;96;68;66 16:01:48 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;86;86;87;86;94;86;86;78;98;107 16:01:48 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;138;143;141;132;139;141;150;139;176;142 16:01:48 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;171;170;228;203;170;230;181;167;170;171 16:01:48 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;63;65;68;69;66;69;66;68;68;78 16:01:48 INFO - PROCESS | 1672 | __end_tp_report 16:01:48 INFO - PROCESS | 1672 | __start_cc_report 16:01:48 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1077 16:01:48 INFO - PROCESS | 1672 | __end_cc_report 16:01:48 INFO - PROCESS | 1672 | __startTimestamp1462575708393__endTimestamp 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | ------- Summary: start ------- 16:01:48 INFO - PROCESS | 1672 | Number of tests: 14 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.50 stddev:2.25 (1.9%) stddev-sans-first:1.92 16:01:48 INFO - PROCESS | 1672 | Values: 111.0 114.0 111.0 116.0 115.0 115.0 115.0 117.0 117.0 117.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:149.20 Median:153.00 stddev:28.77 (18.8%) stddev-sans-first:30.48 16:01:48 INFO - PROCESS | 1672 | Values: 153.0 119.0 147.0 173.0 112.0 142.0 114.0 192.0 187.0 153.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:211.50 Median:199.00 stddev:28.29 (14.2%) stddev-sans-first:29.36 16:01:48 INFO - PROCESS | 1672 | Values: 195.0 198.0 209.0 262.0 267.0 194.0 198.0 200.0 198.0 194.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:82.80 Median:66.50 stddev:33.60 (50.5%) stddev-sans-first:35.09 16:01:48 INFO - PROCESS | 1672 | Values: 66.0 85.0 73.0 141.0 58.0 59.0 58.0 67.0 148.0 73.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:82.00 stddev:20.05 (24.5%) stddev-sans-first:20.41 16:01:48 INFO - PROCESS | 1672 | Values: 81.0 97.0 129.0 79.0 81.0 89.0 124.0 83.0 85.0 122.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:2.40 (2.8%) stddev-sans-first:2.07 16:01:48 INFO - PROCESS | 1672 | Values: 91.0 86.0 87.0 84.0 89.0 84.0 90.0 87.0 87.0 85.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:94.50 stddev:8.55 (9.0%) stddev-sans-first:8.93 16:01:48 INFO - PROCESS | 1672 | Values: 94.0 96.0 94.0 121.0 94.0 95.0 96.0 96.0 104.0 93.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:122.00 Median:148.00 stddev:23.88 (16.1%) stddev-sans-first:24.62 16:01:48 INFO - PROCESS | 1672 | Values: 138.0 142.0 90.0 119.0 90.0 146.0 132.0 91.0 122.0 150.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:61.50 stddev:6.08 (9.9%) stddev-sans-first:6.21 16:01:48 INFO - PROCESS | 1672 | Values: 58.0 60.0 59.0 62.0 61.0 62.0 60.0 79.0 65.0 60.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:70.50 stddev:8.99 (12.8%) stddev-sans-first:9.29 16:01:48 INFO - PROCESS | 1672 | Values: 66.0 67.0 67.0 70.0 71.0 72.0 75.0 96.0 68.0 66.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:86.00 stddev:8.15 (9.5%) stddev-sans-first:8.56 16:01:48 INFO - PROCESS | 1672 | Values: 86.0 86.0 87.0 86.0 94.0 86.0 86.0 78.0 98.0 107.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:141.50 stddev:12.08 (8.5%) stddev-sans-first:12.61 16:01:48 INFO - PROCESS | 1672 | Values: 138.0 143.0 141.0 132.0 139.0 141.0 150.0 139.0 176.0 142.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:176.00 stddev:24.93 (14.2%) stddev-sans-first:25.83 16:01:48 INFO - PROCESS | 1672 | Values: 171.0 170.0 228.0 203.0 170.0 230.0 181.0 167.0 170.0 171.0 16:01:48 INFO - PROCESS | 1672 | 16:01:48 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:4.00 (5.9%) stddev-sans-first:3.81 16:01:48 INFO - PROCESS | 1672 | Values: 63.0 65.0 68.0 69.0 66.0 69.0 66.0 68.0 68.0 78.0 16:01:48 INFO - PROCESS | 1672 | -------- Summary: end -------- 16:01:48 INFO - PROCESS | 1672 | 16:01:49 INFO - TEST-INFO | 1672: exit 0 16:01:49 INFO - TEST-OK | kraken | took 60229ms 16:01:49 INFO - SUITE-END | took 1172s 16:01:49 INFO - Completed test suite (00:19:33) 16:01:49 INFO - javascript benchmark 16:01:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3511.6137224905256, 3515.0, 3543.8582456701733, 3557.039599308187, 3570.1610121621425, 5769.682667453289, 5870.823875283738, 5966.13491043799, 5973.402659734024, 5980.581359304851, 5321.0, 5813.505852002585, 5897.4102589741115, 5908.995470769969, 5968.045112781945, 5238.895222095565, 5282.181261904411, 5351.170568561879, 5358.946410535884, 5391.32608423947, 506.6749102740341, 5252.8424147275455, 5317.601179911503, 5318.202269659562, 5330.813421530247, 5309.628326017161, 5310.150375939831, 5317.175837745156, 5352.625316227865, 5372.7313634318125, 5358.624896257264, 5359.678419294816, 5388.084025715638, 5392.91910621341, 5396.622236443451, 5216.087184742655, 5221.451747566527, 5291.047611429941, 5303.178007408857, 5330.093884039684, 5659.688717120557, 5820.679862607555, 5866.76532938682, 5887.293524777011, 5904.1734157218025, 6159.753609855646, 6169.321374648785, 6230.937690623126, 6237.438630523275, 6237.563370564062, 6177.351378105324, 6228.003519436868, 6228.470580000705, 6300.621962682208, 6309.87380252397, 6060.666663333515, 6065.3934606539, 6070.757169713206, 6077.878442431127, 6113.694315284218, 6178.567500274938, 6272.2159730033745, 6409.839754006094, 6421.839454013687, 6427.55007149495, 4837.637177211724, 5310.654807437504, 6068.696565171812, 6173.938260617426, 6241.157443745127, 6232.25212974446, 6256.5307601930035, 6323.7786677466065, 6325.462335701425, 6332.68336583169, 4692.976535117303, 6145.740123274718, 6148.938510614926, 6216.968915155486, 6232.18981532407, 6176.413240742122, 6215.316315205324, 6221.0, 6272.2159730033745, 6318.557700960889, 4056.452378928807, 4522.005158865044, 5038.748062596929, 5040.193569028937, 5047.545720885138, 4361.869143925687, 4498.6626003049905, 4515.841945531891, 4541.886452838639, 4597.011642496813, 5814.883702325929, 5911.645301281937, 5937.109433584997, 6041.788537401258, 6053.576249662569, 6274.498040156776, 6311.33730958252, 6335.524835637346, 6336.778212762531, 6341.175647165839, 5899.292084949833, 6028.57799953999, 6047.788327408608, 6059.030555111161, 6100.603460775035, 4959.082569724613, 5153.458886816905, 5493.917591236134, 5495.807646732345, 5533.861653458615, 6499.54503184782, 6521.673916304071, 6533.640649764214, 6547.443467305237, 6568.901466478007, 6148.385161483816, 6205.503559715122, 6243.719032643451, 6247.656378899204, 6325.7153428094925, 5929.792457263975, 6280.309165991828, 6552.868942621216, 6566.868662626625, 6569.605823650596, 5671.631343962629, 5819.476247137609, 6146.924288249628, 6202.34875338101, 6268.247810262797, 6172.753089876534, 6200.3799620037635, 6235.127082208585, 6294.968525157345, 6322.620642761449, 6206.751729930752, 6236.906446403398, 6242.5942313749465, 6243.531735119794, 6287.874242515032, 6112.5946701277735, 6134.0, 6159.23009623797, 6222.191115155001, 6263.96868015657, 6057.515398768176, 6093.177421048102, 6127.816165515042, 6140.749659041634, 6165.290991536031, 5346.492083252085, 6050.515958723204, 6210.937890621217, 6217.4715149212825, 6237.906431403442, 5087.694738315713, 5971.790987315423, 6105.399096011559, 6152.907706384497, 6167.444929956326], "value": 5727.468365578877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5067360670558, 548.2406866489911, 553.4382601659316, 577.8587290102049, 579.32219303415, 38123.80938095309, 38601.03497412566, 39865.80067099661, 39883.80058099713, 39948.800255998714, 40374.59625403745, 41113.58886411135, 41149.0, 41161.970950726245, 41205.79397103018, 8638.913610863905, 9397.201237894787, 11841.703957401047, 12109.939450302736, 12121.212121212093, 11767.823482647767, 11816.231944923595, 11827.34949577773, 11873.050155987543, 11950.641480755548, 10103.292769506137, 11809.763804723943, 11880.0, 11915.28508289501, 11954.222975506607, 11687.941560292229, 11804.0, 11808.0, 11885.34630595317, 11897.048236141089, 8691.608877600524, 11655.708607284801, 11687.824682629682, 11693.649190524298, 12024.759504809856, 42603.9349016274, 42888.57111428877, 43406.0, 43458.913527161756, 43726.562734372565, 3205.5512228288057, 3245.7565682573663, 3350.31318579693, 3392.270661807723, 3652.3060618482405, 29833.165849244288, 30377.848110759525, 30553.694463055308, 30612.693873061206, 30835.537466938014, 2755.2698534888273, 2806.9438611227865, 2821.8306901585765, 2925.3417980954114, 3013.9397212055637, 465559.6722016402, 535106.0, 535141.3242933799, 538273.0, 539593.0, 66514.66742666256, 66784.0, 67202.32797672058, 71572.64213678899, 71897.0, 69689.65155174192, 74866.8769968451, 74869.87695184577, 74896.62551687207, 75159.62420187864, 15809.920950395175, 17508.38720644797, 17675.646487070444, 17868.821311786975, 17921.910390447963, 13158.0, 13302.334883255991, 13483.460661573623, 13563.389647465889, 13862.584122476343, 20518.0, 21181.0, 21206.893965530384, 21261.36215913525, 21321.68017479739, 7118.857622847618, 7228.783136505913, 7726.4591478597085, 8014.479058861272, 8418.789530261793, 784.5136015670207, 1001.9899801001897, 1047.1361127070045, 1087.2660953856116, 1107.9806577948307, 29654.14496115491, 30022.84988575043, 30089.699103009127, 30367.544486832714, 31126.0, 1075.763332066944, 1117.4133579870634, 1130.3218069158438, 1175.0540814644123, 1222.4254600337827, 15517.068975861484, 15601.375944962074, 16050.0, 16073.035617862966, 16284.185790710417, 660433.0, 664863.0, 674371.0, 675084.0, 677629.0, 62261.377386226464, 62516.0, 62696.05955910665, 63199.736005279636, 64034.6798266015, 1454.3964254834336, 1470.0958910270108, 1476.763717805146, 1491.0755331694636, 1497.3636204612997, 30270.697293027668, 30476.238094046937, 30523.38953220879, 30578.0, 30613.0, 51767.741161293954, 51865.22202166895, 52520.737396312776, 52947.73526132345, 53552.732236340125, 525814.0, 528642.0, 531803.3409832926, 531909.0, 532718.0, 26121.869390652926, 44904.77547612241, 45836.74856560186, 45959.851003725176, 47004.52995470002, 48272.75863620659, 48291.27563086486, 48965.755171223915, 50729.23906141337, 50819.491805081474, 34569.308613828085, 35615.10962225965, 38918.80540597279, 40768.79615601903, 43073.78463107665, 29467.705322947353, 38999.80500097481, 40351.1929761409, 40761.79619101885, 40876.56932007366, 38742.0, 39042.2191556173, 39529.407058893565, 39871.2025759489, 40192.59807401888, 34095.31809363749, 36069.63930360663, 36828.447573285885, 37068.25863482769, 37256.62743372531, 22229.888850555642, 23677.0, 23780.76219237855, 25750.0, 25897.870510647324], "value": 21329.22404071844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.4206973678957, 394.1781385810585, 397.9263836190304, 398.16186926519674, 418.87224396559054, 20508.48728781781, 20856.582868342623, 21898.671519927175, 21918.342449726493, 21983.780162198374, 11768.529258829636, 12153.635390938263, 12382.876171238286, 12410.503579856817, 12497.125201235916, 5179.948200517984, 5337.2794672719265, 5537.0863807471715, 5557.499825015748, 5564.304461942257, 6100.664463454508, 6164.10620460033, 6192.690365481731, 6201.16284301618, 6221.222347206599, 5845.8830823383505, 6138.14066030756, 6143.0, 6198.0, 6218.626882387071, 6078.939210607881, 6118.204633397652, 6121.326654068072, 6139.6009259398, 6161.045038019114, 5745.54035677149, 6005.159277701126, 6033.396660333975, 6042.093685947099, 6055.182550355689, 13550.796738048937, 13939.860601393957, 14092.0, 14111.576652700436, 14144.151350918977, 1964.7052942058663, 2070.89645517725, 2073.232903825579, 2100.831933445321, 2125.7661657217695, 8132.146124656944, 8269.793255168608, 9625.374350667184, 9877.555510002067, 9898.024887318285, 1729.2564197395116, 1800.7028840241344, 1801.5946412057313, 1815.509812350658, 1827.269092363052, 127327.72672273344, 132743.0, 132986.0, 133659.66340336666, 135852.0, 9544.61821527145, 11618.360990145624, 11821.822672659917, 11878.524859005716, 11881.821772673416, 15216.619584510476, 15415.229238538028, 15424.305906234247, 15448.691026179638, 15456.613584660474, 8744.650213991368, 8809.691660791841, 8811.206991370807, 8812.8678069829, 8840.071792461698, 8620.094890036456, 8682.956585217033, 8691.391602587759, 8807.251383632463, 8852.114788521096, 4430.379746835446, 4679.3916790816975, 4715.4577223619035, 4737.076270127361, 4796.640251981115, 1667.7081510735572, 1859.9535011624819, 2024.2813801100692, 2027.260050081739, 2033.013988215714, 877.9253763430053, 949.8290307744534, 958.2190514730472, 996.6594929819381, 1102.32207192576, 20541.178352865718, 20766.896165519378, 20816.47958801042, 21130.47173820636, 21137.471563211042, 935.1723724503863, 963.2246041936169, 968.4625033106553, 981.2248323824242, 994.1251698505414, 10706.250562460553, 10997.450127493594, 11001.889981100247, 11011.504482298316, 11068.225224234226, 565683.1715841395, 611473.0, 611889.0, 628329.0, 632815.0, 110875.0, 129412.0, 137300.25399492154, 141493.0, 144505.0, 977.154761131616, 1024.774549599087, 1027.635189507729, 1050.4642632257603, 1062.4528367890389, 18336.0, 18585.88484690923, 18616.348427804962, 18695.532611684815, 18783.906080470057, 49619.50380496149, 52532.0, 55322.72338638281, 55567.0, 55933.16100258581, 80719.0, 81029.0, 81374.18625813903, 81435.0, 83731.0, 7018.613976231356, 7469.88795168062, 7516.924830751841, 7746.845063098819, 8648.221660050625, 9202.861957070783, 11778.0, 12176.330301833485, 12515.37423128858, 12807.871921280668, 9986.101250887456, 11046.005859472685, 11203.663890083311, 11343.071677814627, 11538.019268361948, 10878.891211087788, 10892.128629709447, 10912.072473839813, 11169.38568378747, 11393.715157120807, 9760.316777825485, 10832.97086776755, 11399.430028498376, 11468.655940321803, 11735.882641173821, 7451.627418628938, 7574.848503030019, 7660.693572257047, 7738.800485924466, 7776.805579860549, 9962.0, 10609.522571484147, 11194.552217911192, 11426.600068997544, 11660.30038197711], "value": 9352.01643297521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1616.25652199988, 1644.55596988813, 1916.9233230670766, 1936.3900371383022, 1971.921123155073, 7981.800454988614, 8155.714549990752, 8591.312694984403, 9093.408928419649, 9120.863187052191, 8651.221390074892, 8741.344399170055, 8867.024627290994, 8870.689525866595, 8890.333225008135, 2150.720321408767, 2437.695288088989, 2461.323136137559, 2469.6912885889265, 2478.2565230430887, 1990.3033938121687, 2087.6242276390244, 2330.8484948478294, 2464.039024780337, 2487.253823852846, 2440.2069327468644, 2443.328084776692, 2452.3746444656585, 2464.691913510811, 2468.06213638817, 2067.3177851309033, 2434.8904299306487, 2437.5124975004983, 2445.9143929962543, 2455.4966231922413, 2090.979090209109, 2404.2787163850776, 2412.5657381671294, 2426.551088048717, 2448.7428819973825, 7797.415193860426, 8803.691870784556, 8899.777505562348, 8930.240929520998, 9204.263658907339, 5314.229436731653, 5472.945270547283, 5502.779888804443, 5505.229267902497, 5510.090835012258, 3612.313660404515, 3630.364686179908, 3661.9633803662155, 3707.258548290353, 3708.072981754561, 1042.5308611125024, 1047.9109275711573, 1063.2769716592713, 1065.3288428290127, 1066.3122286125483, 11683.357415342152, 11802.881971180264, 11807.822882656767, 11817.113716471213, 11849.111316651286, 4774.570288674036, 4847.2729090636685, 4949.925751113737, 4977.104121258136, 4992.126377883928, 9858.753531161636, 9861.211103111735, 9983.850242246372, 10028.448435336126, 10093.899061009295, 5415.214793854869, 5423.105187643994, 5482.122860342326, 5497.36780270274, 5505.587080968943, 4456.910861782746, 4850.272459131088, 5009.7244651544515, 5029.497050295015, 5036.5718913892, 4051.825218817059, 4069.8372065117655, 4112.773797441109, 4133.359329303963, 4135.421041054255, 1360.7142500074897, 1375.3948262764352, 1376.2980879751199, 1376.9586912392645, 1386.473140206715, 917.2111983694018, 944.15498129174, 947.7962238118838, 951.4624237306039, 988.8269552828226, 5246.664875996027, 5993.640381577119, 6077.453029227391, 6100.48145907594, 6112.869119212871, 423.60393032514696, 456.33375272103103, 469.7416420968454, 475.8453502611665, 478.810869706464, 3962.6235507627307, 3982.9601703983167, 4006.5760215069463, 4072.1041370898356, 4072.552019277877, 8976.955115224382, 10423.895761042293, 10847.891521084846, 10908.50911708975, 10920.344779313267, 10753.193510486744, 10761.139108871272, 10815.945920270347, 10868.45657717111, 10888.564457421615, 7415.703371865173, 8301.837742716025, 8342.457740246864, 8379.832403351898, 8398.118197589287, 4049.979750101231, 5772.942270577409, 5827.1353166986155, 5829.59192856496, 5919.348871624204, 9726.0, 10106.494675266355, 10111.443870587189, 10128.848067279145, 10186.236032297755, 9903.405795652285, 10291.53688084023, 10311.329763565343, 10331.380117192992, 10383.376997380181, 6941.271166527442, 7187.9640601796655, 7340.486165968331, 7566.167721550735, 7623.618819059136, 8289.171082891662, 8325.167483251626, 8351.916480835356, 8409.705660302105, 8494.023187333536, 7431.071116110486, 7503.399728021855, 7564.924350756422, 7655.846883062419, 7703.229677032252, 6676.599404035774, 6914.792556223321, 7032.0, 7301.817454563678, 7343.1555371133, 6983.790486285419, 7071.752488662872, 7110.146782386147, 7116.715331386894, 7120.465965052538, 4680.3915490985955, 4687.531246875285, 4815.22956326986, 4994.9750251248515, 5051.065552872583, 4908.141075311734, 4991.900161996812, 5033.219850923113, 5096.184610462308, 5239.135542635422], "value": 4554.4469062996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.8637485881071, 320.63607805141174, 325.2373184881452, 326.0755757427691, 330.0165506789768, 4889.535494128057, 4921.237208232729, 5167.018266529361, 5502.80740174094, 5607.747651355683, 4820.614350851933, 5470.589705772073, 5499.477549632778, 5502.614816962804, 5538.390777014525, 3966.781826999521, 4091.2635725569385, 4169.97915010426, 4207.873763787091, 4208.579142085798, 3490.703290220334, 4110.219058378913, 4178.958210417887, 4182.20538097762, 4204.201201771669, 3611.494390785292, 3800.5059342285604, 4157.916841663165, 4184.949374806338, 4241.384999175118, 3637.1452708613456, 4097.672186225095, 4102.241085399196, 4161.542230354658, 4194.727342722713, 2889.1188187602775, 3185.152611485356, 3463.4631632097066, 3615.9513741015076, 3618.4391419330045, 4386.758728269944, 4692.507286734878, 5028.472010438924, 5307.442718514539, 5366.490183432567, 3778.225463779946, 4269.978650106729, 4668.229742092551, 4890.557285600757, 4899.383203542822, 5141.794328226866, 5592.468715472024, 5742.7415766290615, 5811.709414529299, 5819.359870414251, 3764.247150569897, 3957.742746721454, 3973.9602603974167, 3988.4216788565786, 3991.880243592697, 5688.829335119953, 5720.370759216441, 5761.53907687384, 5997.0, 6236.251649802008, 3327.800331980089, 3673.4673472346362, 3975.9463742108364, 4017.5580686124504, 4037.9097643636055, 4091.2635725569685, 4100.91798164035, 4109.91780164395, 4119.958800412018, 4120.938185927213, 4780.282957556395, 4800.66395352329, 4823.059503396804, 4838.709677419366, 4839.491853355417, 5230.398504172069, 5241.842744717665, 5387.868547605047, 5393.919091213635, 5914.645121292736, 1198.4127777389017, 1225.9570915017932, 1292.8060790881443, 1333.8328962158112, 1338.9598312050655, 837.5835713965254, 853.6756032707656, 880.9560670605354, 901.3104974694364, 904.4799240436776, 4833.0, 5044.596432285409, 5223.921641175385, 5231.24146998683, 5293.311869456946, 5567.331920169606, 5615.0454422748235, 5736.77052917887, 5763.538916886639, 5794.507466865363, 4300.849470268588, 5029.572486338702, 5103.413107492612, 5212.582993360522, 5248.58565060069, 2583.147561304765, 3019.958114450555, 3021.6402618821617, 3038.9544156837665, 3042.741366983787, 5161.638685292069, 5205.687658740488, 6034.728437220336, 6063.757449701962, 6095.512359011357, 4612.0, 5130.666026833051, 5463.623323854056, 5733.0, 5955.642661440327, 5384.703841288767, 5670.603057785916, 5747.137919322185, 5845.983135818136, 5855.268091488564, 4899.485554016919, 5063.518965698253, 5127.282360409305, 5475.397706252389, 5669.659820410789, 4891.217405215149, 5155.510226528474, 5215.426303106731, 5657.519110875622, 5732.455416735404, 5506.421825708403, 5631.535800691768, 5634.605577609528, 6039.335673075871, 6040.6677632730625, 3230.53157292196, 3263.8041717497026, 3790.995386222598, 3805.942910856395, 3808.3906574947873, 5034.748262586929, 5737.96302312673, 5749.975017487692, 5758.62568933018, 5780.508656764222, 5292.364916210079, 5319.361676598833, 5527.623122057554, 5540.972295138498, 5576.275084239185, 5091.261767043832, 5121.001404726043, 5555.722183897646, 5570.972145139248, 5606.0, 4809.927851082307, 4980.850574482772, 5405.540529054917, 5439.3200849893765, 5442.891142177055, 5258.737063146904, 5859.531237501075, 5865.618734782225, 5903.321118071305, 5909.231799866162, 4927.778249978688, 5164.0, 5707.714614269187, 5764.1842819513395, 5785.56608254391], "value": 4112.205935944366, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.7278729327329, 703.9401650859677, 721.7726416178905, 751.2112282103786, 753.7248904149986, 12559.37203139844, 14836.554903352902, 15091.698166036673, 15184.620384490394, 15261.923690381533, 14093.365798539066, 14366.281685915716, 14379.92810035951, 14404.423823047091, 14445.133292002487, 724.2901956083042, 909.4679612426738, 910.2308549275867, 932.8973812880578, 936.1060187520901, 894.9239314658262, 926.1201858234671, 936.5504557812254, 937.3906960475712, 938.0853667674031, 949.1837020162654, 995.9701208963743, 995.9741466289734, 996.875390576178, 1001.2540657210388, 939.8825146856643, 983.7442265011113, 985.8225519406504, 986.7286496213562, 988.5946761827652, 693.743314973458, 724.2504008351331, 728.2535401213745, 730.5105579261855, 735.8638651849371, 14584.927075364556, 14828.555143345717, 15043.623909402244, 15056.473023444236, 15088.849111508855, 10589.470526473646, 12752.617421477371, 12825.166364186298, 12933.547325843645, 13177.27524986135, 9560.187384072362, 9580.377275477073, 9946.0, 10066.496675166212, 10202.9489852551, 3124.812511249332, 3162.778605497616, 3164.8259345735974, 3178.1577881861326, 3240.983795081033, 75868.0, 77676.61161694211, 77864.8320275202, 84352.0, 85812.28375432457, 6913.89629155563, 8515.97808263, 9485.525723713787, 9565.808683826284, 9733.977932317146, 20410.69383959242, 20727.27454539084, 21096.47258818511, 21117.155313787276, 21577.568448631253, 5220.530152286236, 7301.379382752525, 7316.0, 7557.168711441737, 7696.384289256846, 4162.791860407028, 4179.185058913483, 4188.937165942513, 4206.684498662613, 4223.894402639959, 3431.9485207721905, 3562.4656301554455, 3566.928661426757, 3569.696575791035, 3583.7849729016343, 633.3160186998031, 640.1997503121098, 646.1503123392723, 646.5280345347923, 649.7238673563717, 483.4826735393166, 505.23709199108873, 540.978360865561, 548.6762809942073, 576.9048107062372, 7592.468527203043, 7719.768406947801, 7907.169747176578, 8054.395920306, 8082.515223064496, 474.45437746591693, 498.77056553985574, 514.5677630790154, 532.89608526337, 575.1487798058896, 3090.8454577271045, 3770.7171962102952, 3774.245150969762, 3778.4899038629983, 3901.49280593521, 86737.56631216929, 87106.56446717726, 87167.82080448039, 87315.56342218249, 89088.5545572268, 67751.64496710038, 74041.62979185177, 77931.83102253471, 78409.82385264112, 78810.0, 69568.30431695719, 70446.64776676186, 70893.0, 72456.0, 72734.0, 8338.523320063547, 8353.916460835557, 8429.0, 8487.405881588229, 8588.441751286146, 42656.78671606622, 43093.0, 43096.70709878708, 43152.136957261304, 43971.0, 55578.72210639083, 58306.0, 58344.0, 61483.38516614777, 63072.68463657652, 10975.945120274348, 11067.944660276647, 11274.492647830702, 11453.484593193492, 11584.247023943417, 11950.760984780429, 12039.75920481603, 12089.637310880687, 12252.632421027383, 12293.815592766294, 11356.94321528387, 11358.716032099264, 11412.942935285602, 11793.646190613952, 11842.230255033395, 11416.597313042366, 11557.711057223636, 11593.014593759624, 11775.352355620524, 11995.340256285988, 11263.324200547993, 11274.267172633752, 11336.94331528337, 11718.355490447766, 11724.0, 7352.0, 9345.53272336394, 9398.107179817905, 9722.27082968766, 10134.543945522617, 9843.2125429964, 9879.901200987899, 9880.45657488845, 9927.702168934944, 9933.503324833873], "value": 6766.047754036662, "unit": "score"}], "name": "dromaeo_css", "value": 7246.408143904615, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 111.0, 116.0, 115.0, 115.0, 115.0, 117.0, 117.0, 117.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 147.0, 173.0, 112.0, 142.0, 114.0, 192.0, 187.0, 153.0], "value": 149.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 209.0, 262.0, 267.0, 194.0, 198.0, 200.0, 198.0, 194.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 73.0, 141.0, 58.0, 59.0, 58.0, 67.0, 148.0, 73.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 97.0, 129.0, 79.0, 81.0, 89.0, 124.0, 83.0, 85.0, 122.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 87.0, 84.0, 89.0, 84.0, 90.0, 87.0, 87.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 94.0, 121.0, 94.0, 95.0, 96.0, 96.0, 104.0, 93.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 90.0, 119.0, 90.0, 146.0, 132.0, 91.0, 122.0, 150.0], "value": 122.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 62.0, 61.0, 62.0, 60.0, 79.0, 65.0, 60.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 70.0, 71.0, 72.0, 75.0, 96.0, 68.0, 66.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 94.0, 86.0, 86.0, 78.0, 98.0, 107.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 141.0, 132.0, 139.0, 141.0, 150.0, 139.0, 176.0, 142.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 228.0, 203.0, 170.0, 230.0, 181.0, 167.0, 170.0, 171.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 68.0, 69.0, 66.0, 69.0, 66.0, 68.0, 68.0, 78.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1584.5999999999997, "alertThreshold": 2.0}]} 16:01:49 INFO - Return code: 0 16:01:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:01:49 ERROR - # TBPL SUCCESS # 16:01:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:49 INFO - 2016-05-06 16:01:49.690 screenresolution[1677:10165] starting screenresolution argv=screenresolution get 16:01:49 INFO - 2016-05-06 16:01:49.692 screenresolution[1677:10165] Display 0: 1600x1200x32@60 16:01:49 INFO - 2016-05-06 16:01:49.698 screenresolution[1678:10168] starting screenresolution argv=screenresolution list 16:01:49 INFO - Available Modes on Display 0 16:01:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:49 INFO - Intel Iris: 16:01:49 INFO - Chipset Model: Intel Iris 16:01:49 INFO - Type: GPU 16:01:49 INFO - Bus: Built-In 16:01:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:49 INFO - Vendor: Intel (0x8086) 16:01:49 INFO - Device ID: 0x0a2e 16:01:49 INFO - Revision ID: 0x0009 16:01:49 INFO - Displays: 16:01:49 INFO - Display: 16:01:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:49 INFO - Display Serial Number: 1600x1200 60 16:01:49 INFO - Main Display: Yes 16:01:49 INFO - Mirror: Off 16:01:49 INFO - Online: Yes 16:01:49 INFO - Rotation: Supported 16:01:49 INFO - Return code: 0 16:01:49 INFO - Running post-action listener: _resource_record_post_action 16:01:49 INFO - ##### 16:01:49 INFO - ##### Finished run-tests step (success) 16:01:49 INFO - ##### 16:01:49 INFO - Running post-run listener: _resource_record_post_run 16:01:50 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 37733888; Write bytes: 698121216; Read time: 429; Write time: 2047 16:01:50 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 392718848; Write bytes: 387159552; Read time: 28674; Write time: 725 16:01:50 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 35902976; Write bytes: 297776640; Read time: 409; Write time: 1285 16:01:50 INFO - Running post-run listener: _upload_blobber_files 16:01:50 INFO - Blob upload gear active. 16:01:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:01:51 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 16:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:01:51 INFO - (blobuploader) - INFO - Open directory for files ... 16:01:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:01:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:01:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:52 INFO - (blobuploader) - INFO - Done attempting. 16:01:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:01:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:01:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:53 INFO - (blobuploader) - INFO - Done attempting. 16:01:53 INFO - (blobuploader) - INFO - Iteration through files over. 16:01:53 INFO - Return code: 0 16:01:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:01:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:01:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/527312d5a13afcd979b0d6bf459bafdd5d381896e90784684bdd133b081f6c505af62e30faf38b8be0310df62ea279ae493ba0b32e8712a0ffd1b0f70e3a99e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd73464ebfd56c45f589b2ef34c88bf1cfa3f7e3c30f8a66d7d4cc3442d0b8f5ba1b4852c8fda48abe117aab4b3e759983ddfe967c02e234ff2e837e95781522"} 16:01:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:01:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:01:53 INFO - Contents: 16:01:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/527312d5a13afcd979b0d6bf459bafdd5d381896e90784684bdd133b081f6c505af62e30faf38b8be0310df62ea279ae493ba0b32e8712a0ffd1b0f70e3a99e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd73464ebfd56c45f589b2ef34c88bf1cfa3f7e3c30f8a66d7d4cc3442d0b8f5ba1b4852c8fda48abe117aab4b3e759983ddfe967c02e234ff2e837e95781522"} 16:01:53 INFO - Running post-run listener: copy_logs_to_upload_dir 16:01:53 INFO - Copying logs to upload dir... 16:01:53 INFO - mkdir: /builds/slave/test/build/upload/logs 16:01:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.983874 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 16:01:53.198717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 16:01:53.202424) ========= 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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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/Fx-Team/sha512/527312d5a13afcd979b0d6bf459bafdd5d381896e90784684bdd133b081f6c505af62e30faf38b8be0310df62ea279ae493ba0b32e8712a0ffd1b0f70e3a99e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd73464ebfd56c45f589b2ef34c88bf1cfa3f7e3c30f8a66d7d4cc3442d0b8f5ba1b4852c8fda48abe117aab4b3e759983ddfe967c02e234ff2e837e95781522"} build_url:https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/b3d6LpclQpmeQPZQ1-LdqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/527312d5a13afcd979b0d6bf459bafdd5d381896e90784684bdd133b081f6c505af62e30faf38b8be0310df62ea279ae493ba0b32e8712a0ffd1b0f70e3a99e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd73464ebfd56c45f589b2ef34c88bf1cfa3f7e3c30f8a66d7d4cc3442d0b8f5ba1b4852c8fda48abe117aab4b3e759983ddfe967c02e234ff2e837e95781522"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 16:01:53.229787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:01:53.230120) ========= 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.YQIWVh1X7Q/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.ApKvxWwMkw/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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.004987 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 16:01:53.278518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 16:01:53.278842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 16:01:53.279145) ========= ========= Total master_lag: 0.14 =========