builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1461093687.67 results: success (0) buildid: 20160419102742 builduid: cea07ba26b334d06a1612cfd872f6d79 revision: 3b3c1809c8be95512093a3cf19d1a35e99ad145c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.665370) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.665891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.666174) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.685874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.686251) ========= 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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-04-19 12:21:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.62M=0.002s 2016-04-19 12:21:27 (6.62 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.829406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.829736) ========= 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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.022373 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:27.878854) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-19 12:21:27.879200) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3b3c1809c8be95512093a3cf19d1a35e99ad145c --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 3b3c1809c8be95512093a3cf19d1a35e99ad145c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-04-19 12:21:27,958 truncating revision to first 12 chars 2016-04-19 12:21:27,958 Setting DEBUG logging. 2016-04-19 12:21:27,958 attempt 1/10 2016-04-19 12:21:27,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3b3c1809c8be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 12:21:29,635 unpacking tar archive at: fx-team-3b3c1809c8be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.934526 ========= master_lag: 2.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-19 12:21:32.117673) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:32.117994) ========= script_repo_revision: 3b3c1809c8be95512093a3cf19d1a35e99ad145c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 12:21:32.118389) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-04-19 12:21:32.118659) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-04-19 12:21:40.317037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-19 12:21:40.317398) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:21:42 INFO - MultiFileLogger online at 20160419 12:21:42 in /builds/slave/test 12:21:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:21:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:42 INFO - {'append_to_log': False, 12:21:42 INFO - 'base_work_dir': '/builds/slave/test', 12:21:42 INFO - 'blob_upload_branch': 'Fx-Team', 12:21:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:42 INFO - 'branch': 'Fx-Team', 12:21:42 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:42 INFO - 'config_files': ('talos/mac_config.py',), 12:21:42 INFO - 'default_actions': ('clobber', 12:21:42 INFO - 'read-buildbot-config', 12:21:42 INFO - 'download-and-extract', 12:21:42 INFO - 'populate-webroot', 12:21:42 INFO - 'create-virtualenv', 12:21:42 INFO - 'install', 12:21:42 INFO - 'run-tests'), 12:21:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:42 INFO - 'download_minidump_stackwalk': True, 12:21:42 INFO - 'download_symbols': 'ondemand', 12:21:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:42 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:42 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:42 INFO - 'installer_path': 'installer.exe', 12:21:42 INFO - 'log_level': 'info', 12:21:42 INFO - 'log_name': 'talos', 12:21:42 INFO - 'log_to_console': True, 12:21:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:42 INFO - 'opt_config_files': (), 12:21:42 INFO - 'pip_index': False, 12:21:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:42 INFO - 'cmd': ('bash', 12:21:42 INFO - '-c', 12:21:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:42 INFO - 'enabled': True, 12:21:42 INFO - 'halt_on_failure': False, 12:21:42 INFO - 'name': 'check_screen_resolution'},), 12:21:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:42 INFO - 'cmd': ('bash', 12:21:42 INFO - '-c', 12:21:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:42 INFO - 'enabled': True, 12:21:42 INFO - 'halt_on_failure': False, 12:21:42 INFO - 'name': 'check_screen_resolution'},), 12:21:42 INFO - 'run_cmd_checks_enabled': True, 12:21:42 INFO - 'sps_profile': False, 12:21:42 INFO - 'sps_profile_interval': 0, 12:21:42 INFO - 'suite': 'dromaeojs', 12:21:42 INFO - 'system_bits': '32', 12:21:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:42 INFO - 'title': 't-yosemite-r7-0034', 12:21:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:42 INFO - 'use_talos_json': True, 12:21:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:42 INFO - 'work_dir': 'build'} 12:21:42 INFO - ##### 12:21:42 INFO - ##### Running clobber step. 12:21:42 INFO - ##### 12:21:42 INFO - Running pre-action listener: _resource_record_pre_action 12:21:42 INFO - Running main action method: clobber 12:21:42 INFO - rmtree: /builds/slave/test/build 12:21:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:42 INFO - Running post-action listener: _resource_record_post_action 12:21:42 INFO - ##### 12:21:42 INFO - ##### Running read-buildbot-config step. 12:21:42 INFO - ##### 12:21:42 INFO - Running pre-action listener: _resource_record_pre_action 12:21:42 INFO - Running main action method: read_buildbot_config 12:21:42 INFO - Using buildbot properties: 12:21:42 INFO - { 12:21:42 INFO - "project": "", 12:21:42 INFO - "product": "firefox", 12:21:42 INFO - "script_repo_revision": "production", 12:21:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:21:42 INFO - "repository": "", 12:21:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:21:42 INFO - "buildid": "20160419102742", 12:21:42 INFO - "pgo_build": "False", 12:21:42 INFO - "basedir": "/builds/slave/test", 12:21:42 INFO - "buildnumber": 639, 12:21:42 INFO - "slavename": "t-yosemite-r7-0034", 12:21:42 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:21:42 INFO - "platform": "yosemite_r7", 12:21:42 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:21:42 INFO - "branch": "fx-team", 12:21:42 INFO - "repo_path": "integration/fx-team", 12:21:42 INFO - "revision": "3b3c1809c8be95512093a3cf19d1a35e99ad145c", 12:21:42 INFO - "stage_platform": "macosx64", 12:21:42 INFO - "builduid": "cea07ba26b334d06a1612cfd872f6d79", 12:21:42 INFO - "slavebuilddir": "test" 12:21:42 INFO - } 12:21:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 12:21:42 INFO - Running post-action listener: _resource_record_post_action 12:21:42 INFO - ##### 12:21:42 INFO - ##### Running download-and-extract step. 12:21:42 INFO - ##### 12:21:42 INFO - Running pre-action listener: _resource_record_pre_action 12:21:42 INFO - Running main action method: download_and_extract 12:21:42 INFO - mkdir: /builds/slave/test/build/tests 12:21:42 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')]} 12:21:42 INFO - https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:21:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:21:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:21:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 12:21:44 INFO - Downloaded 1199 bytes. 12:21:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:21:44 INFO - Using the following test package requirements: 12:21:44 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:21:44 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 12:21:44 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 12:21:44 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'jsshell-mac.zip'], 12:21:44 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 12:21:44 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:21:44 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 12:21:44 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 12:21:44 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 12:21:44 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:21:44 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 12:21:44 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:44 INFO - https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:21:45 INFO - Downloaded 18039395 bytes. 12:21:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:48 INFO - Return code: 0 12:21:48 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:48 INFO - https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:21:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:21:49 INFO - Downloaded 18039395 bytes. 12:21:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:53 INFO - Return code: 0 12:21:53 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')]} 12:21:53 INFO - https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:21:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:21:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:21:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:21:54 INFO - Downloaded 10845952 bytes. 12:21:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:21:54 INFO - Return code: 0 12:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:54 INFO - https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:21:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:21:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:21:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 12:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 12:21:57 INFO - Downloaded 109942832 bytes. 12:21:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:21:57 INFO - mkdir: /builds/slave/test/properties 12:21:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:57 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:57 INFO - Contents: 12:21:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:21:58 INFO - Running post-action listener: _resource_record_post_action 12:21:58 INFO - Running post-action listener: set_extra_try_arguments 12:21:58 INFO - ##### 12:21:58 INFO - ##### Running populate-webroot step. 12:21:58 INFO - ##### 12:21:58 INFO - Running pre-action listener: _resource_record_pre_action 12:21:58 INFO - Running main action method: populate_webroot 12:21:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:58 INFO - '--tppagecycles', 12:21:58 INFO - '7'], 12:21:58 INFO - 'tests': ['tsvgm']}}, 12:21:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['damp', 'tps']}, 12:21:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'tests': ['damp', 'tps']}, 12:21:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['dromaeo_dom']}, 12:21:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['a11yr', 12:21:58 INFO - 'ts_paint', 12:21:58 INFO - 'tpaint', 12:21:58 INFO - 'sessionrestore', 12:21:58 INFO - 'sessionrestore_no_auto_restore', 12:21:58 INFO - 'tabpaint']}, 12:21:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['tsvgx', 12:21:58 INFO - 'tsvgr_opacity', 12:21:58 INFO - 'tart', 12:21:58 INFO - 'tscrollx', 12:21:58 INFO - 'cart']}, 12:21:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:58 INFO - 'tsvgr_opacity', 12:21:58 INFO - 'tart', 12:21:58 INFO - 'tscrollx', 12:21:58 INFO - 'cart']}, 12:21:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'talos_options': ['--disable-e10s'], 12:21:58 INFO - 'tests': ['tp5o']}, 12:21:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'tests': ['tp5o']}, 12:21:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'talos_options': ['--disable-e10s', 12:21:58 INFO - '--xperf_path', 12:21:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:58 INFO - 'tests': ['tp5n']}, 12:21:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:58 INFO - 'talos_options': ['--xperf_path', 12:21:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:58 INFO - 'tests': ['tp5n']}}, 12:21:58 INFO - 'talos.zip': {'path': '', 12:21:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:58 INFO - Running post-action listener: _resource_record_post_action 12:21:58 INFO - ##### 12:21:58 INFO - ##### Running create-virtualenv step. 12:21:58 INFO - ##### 12:21:58 INFO - Running pre-action listener: _resource_record_pre_action 12:21:58 INFO - Running main action method: create_virtualenv 12:21:58 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:58 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 12:21:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:58 INFO - Using real prefix '/tools/python27' 12:21:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:59 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:02 INFO - Installing pip.................done. 12:22:02 INFO - Return code: 0 12:22:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:02 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:02 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 12:22:02 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 12:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:02 INFO - 'HOME': '/Users/cltbld', 12:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:02 INFO - 'LOGNAME': 'cltbld', 12:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:02 INFO - 'MOZ_NO_REMOTE': '1', 12:22:02 INFO - 'NO_EM_RESTART': '1', 12:22:02 INFO - 'PAGER': '/bin/cat', 12:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:02 INFO - 'PWD': '/builds/slave/test', 12:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:02 INFO - 'SHELL': '/bin/bash', 12:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:02 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:02 INFO - 'USER': 'cltbld', 12:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:02 INFO - 'XPC_FLAGS': '0x0', 12:22:02 INFO - 'XPC_SERVICE_NAME': '0', 12:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:02 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 12:22:02 INFO - Cleaning up... 12:22:02 INFO - Return code: 0 12:22:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:02 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:02 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 12:22:02 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 12:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:02 INFO - 'HOME': '/Users/cltbld', 12:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:02 INFO - 'LOGNAME': 'cltbld', 12:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:02 INFO - 'MOZ_NO_REMOTE': '1', 12:22:02 INFO - 'NO_EM_RESTART': '1', 12:22:02 INFO - 'PAGER': '/bin/cat', 12:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:02 INFO - 'PWD': '/builds/slave/test', 12:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:02 INFO - 'SHELL': '/bin/bash', 12:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:02 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:02 INFO - 'USER': 'cltbld', 12:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:02 INFO - 'XPC_FLAGS': '0x0', 12:22:02 INFO - 'XPC_SERVICE_NAME': '0', 12:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:02 INFO - Downloading/unpacking psutil>=0.7.1 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:02 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 12:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:05 INFO - Installing collected packages: psutil 12:22:05 INFO - Running setup.py install for psutil 12:22:05 INFO - building 'psutil._psutil_osx' extension 12:22:05 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 12:22:06 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 12:22:06 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 12:22:06 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 12:22:06 INFO - building 'psutil._psutil_posix' extension 12:22:06 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 12:22:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:22:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:22:06 INFO - ^ 12:22:06 INFO - 1 warning generated. 12:22:06 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 12:22:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:06 INFO - Successfully installed psutil 12:22:06 INFO - Cleaning up... 12:22:06 INFO - Return code: 0 12:22:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:06 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 12:22:06 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 12:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:06 INFO - 'HOME': '/Users/cltbld', 12:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:06 INFO - 'LOGNAME': 'cltbld', 12:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:06 INFO - 'MOZ_NO_REMOTE': '1', 12:22:06 INFO - 'NO_EM_RESTART': '1', 12:22:06 INFO - 'PAGER': '/bin/cat', 12:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:06 INFO - 'PWD': '/builds/slave/test', 12:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:06 INFO - 'SHELL': '/bin/bash', 12:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:06 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:06 INFO - 'USER': 'cltbld', 12:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:06 INFO - 'XPC_FLAGS': '0x0', 12:22:06 INFO - 'XPC_SERVICE_NAME': '0', 12:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:22:06 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 12:22:06 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 12:22:06 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 12:22:06 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 12:22:06 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 12:22:06 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 12:22:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:22:09 INFO - Installing collected packages: mozsystemmonitor 12:22:09 INFO - Running setup.py install for mozsystemmonitor 12:22:09 INFO - Successfully installed mozsystemmonitor 12:22:09 INFO - Cleaning up... 12:22:09 INFO - Return code: 0 12:22:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:09 INFO - 'HOME': '/Users/cltbld', 12:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:09 INFO - 'LOGNAME': 'cltbld', 12:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:09 INFO - 'MOZ_NO_REMOTE': '1', 12:22:09 INFO - 'NO_EM_RESTART': '1', 12:22:09 INFO - 'PAGER': '/bin/cat', 12:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:09 INFO - 'PWD': '/builds/slave/test', 12:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:09 INFO - 'SHELL': '/bin/bash', 12:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:09 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:09 INFO - 'USER': 'cltbld', 12:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:09 INFO - 'XPC_FLAGS': '0x0', 12:22:09 INFO - 'XPC_SERVICE_NAME': '0', 12:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:12 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22:12 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 12:22:12 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 12:22:12 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 12:22:12 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 12:22:12 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 12:22:12 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 12:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:13 INFO - Downloading docopt-0.6.1.tar.gz 12:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:13 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:13 INFO - Running setup.py install for blobuploader 12:22:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:14 INFO - Running setup.py install for requests 12:22:14 INFO - Running setup.py install for docopt 12:22:14 INFO - Successfully installed blobuploader requests docopt 12:22:14 INFO - Cleaning up... 12:22:14 INFO - Return code: 0 12:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:14 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:14 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 12:22:14 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 12:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:14 INFO - 'HOME': '/Users/cltbld', 12:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:14 INFO - 'LOGNAME': 'cltbld', 12:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:14 INFO - 'MOZ_NO_REMOTE': '1', 12:22:14 INFO - 'NO_EM_RESTART': '1', 12:22:14 INFO - 'PAGER': '/bin/cat', 12:22:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:14 INFO - 'PWD': '/builds/slave/test', 12:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:14 INFO - 'SHELL': '/bin/bash', 12:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:14 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:14 INFO - 'USER': 'cltbld', 12:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:14 INFO - 'XPC_FLAGS': '0x0', 12:22:14 INFO - 'XPC_SERVICE_NAME': '0', 12:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-i953kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8aCybT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Ya5QyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9fatWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6Se8S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tDpbEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-kTkLdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-7nLjo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hHbMFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VQQmYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-FJZNDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-X5fA_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6eQafC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2vPfAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-kl6QGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-IBEz1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0SwS9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:16 INFO - Running setup.py install for manifestparser 12:22:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:17 INFO - Running setup.py install for mozcrash 12:22:17 INFO - Running setup.py install for mozdebug 12:22:17 INFO - Running setup.py install for mozdevice 12:22:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:22:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:17 INFO - Running setup.py install for mozfile 12:22:17 INFO - Running setup.py install for mozhttpd 12:22:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:17 INFO - Running setup.py install for mozinfo 12:22:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Running setup.py install for mozInstall 12:22:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Running setup.py install for mozleak 12:22:18 INFO - Running setup.py install for mozlog 12:22:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Running setup.py install for moznetwork 12:22:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Running setup.py install for mozprocess 12:22:18 INFO - Running setup.py install for mozprofile 12:22:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:18 INFO - Running setup.py install for mozrunner 12:22:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:19 INFO - Running setup.py install for mozscreenshot 12:22:19 INFO - Running setup.py install for moztest 12:22:19 INFO - Running setup.py install for mozversion 12:22:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:19 INFO - Cleaning up... 12:22:19 INFO - Return code: 0 12:22:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:19 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:19 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 12:22:19 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 12:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:19 INFO - 'HOME': '/Users/cltbld', 12:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:19 INFO - 'LOGNAME': 'cltbld', 12:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:19 INFO - 'MOZ_NO_REMOTE': '1', 12:22:19 INFO - 'NO_EM_RESTART': '1', 12:22:19 INFO - 'PAGER': '/bin/cat', 12:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:19 INFO - 'PWD': '/builds/slave/test', 12:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:19 INFO - 'SHELL': '/bin/bash', 12:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:19 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:19 INFO - 'USER': 'cltbld', 12:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:19 INFO - 'XPC_FLAGS': '0x0', 12:22:19 INFO - 'XPC_SERVICE_NAME': '0', 12:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-g03Rzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EkNRcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RPIvba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-XyaH1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YmGUXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4SIiAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-fZFSJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UBeIMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pTQFoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:20 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)) 12:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-whpCk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Y_vDDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-TNU8g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WBi280-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GD455W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-A22Pnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JEalM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:21 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)) 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GIx8Xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:21 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)) 12:22:21 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)) 12:22:21 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)) 12:22:21 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)) 12:22:21 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)) 12:22:21 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)) 12:22:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22:24 INFO - Downloading blessings-1.6.tar.gz 12:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:24 INFO - Installing collected packages: blessings 12:22:24 INFO - Running setup.py install for blessings 12:22:24 INFO - Successfully installed blessings 12:22:24 INFO - Cleaning up... 12:22:24 INFO - Return code: 0 12:22:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:24 INFO - Reading from file tmpfile_stdout 12:22:24 INFO - Current package versions: 12:22:24 INFO - blessings == 1.6 12:22:24 INFO - blobuploader == 1.2.4 12:22:24 INFO - docopt == 0.6.1 12:22:24 INFO - manifestparser == 1.1 12:22:24 INFO - mozInstall == 1.12 12:22:24 INFO - mozcrash == 0.17 12:22:24 INFO - mozdebug == 0.1 12:22:24 INFO - mozdevice == 0.48 12:22:24 INFO - mozfile == 1.2 12:22:24 INFO - mozhttpd == 0.7 12:22:24 INFO - mozinfo == 0.9 12:22:24 INFO - mozleak == 0.1 12:22:24 INFO - mozlog == 3.1 12:22:24 INFO - moznetwork == 0.27 12:22:24 INFO - mozprocess == 0.22 12:22:24 INFO - mozprofile == 0.28 12:22:24 INFO - mozrunner == 6.11 12:22:24 INFO - mozscreenshot == 0.1 12:22:24 INFO - mozsystemmonitor == 0.0 12:22:24 INFO - moztest == 0.7 12:22:24 INFO - mozversion == 1.4 12:22:24 INFO - psutil == 3.1.1 12:22:24 INFO - requests == 1.2.3 12:22:24 INFO - wsgiref == 0.1.2 12:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:24 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')]} 12:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:24 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')]} 12:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:24 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:24 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 12:22:24 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 12:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:24 INFO - 'HOME': '/Users/cltbld', 12:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:24 INFO - 'LOGNAME': 'cltbld', 12:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:24 INFO - 'MOZ_NO_REMOTE': '1', 12:22:24 INFO - 'NO_EM_RESTART': '1', 12:22:24 INFO - 'PAGER': '/bin/cat', 12:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:24 INFO - 'PWD': '/builds/slave/test', 12:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:24 INFO - 'SHELL': '/bin/bash', 12:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:24 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:24 INFO - 'USER': 'cltbld', 12:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:24 INFO - 'XPC_FLAGS': '0x0', 12:22:24 INFO - 'XPC_SERVICE_NAME': '0', 12:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 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)) 12:22:25 INFO - Cleaning up... 12:22:25 INFO - Return code: 0 12:22:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:25 INFO - 'HOME': '/Users/cltbld', 12:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:25 INFO - 'LOGNAME': 'cltbld', 12:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:25 INFO - 'MOZ_NO_REMOTE': '1', 12:22:25 INFO - 'NO_EM_RESTART': '1', 12:22:25 INFO - 'PAGER': '/bin/cat', 12:22:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:25 INFO - 'PWD': '/builds/slave/test', 12:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:25 INFO - 'SHELL': '/bin/bash', 12:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:25 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:25 INFO - 'USER': 'cltbld', 12:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:25 INFO - 'XPC_FLAGS': '0x0', 12:22:25 INFO - 'XPC_SERVICE_NAME': '0', 12:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:25 INFO - Downloading/unpacking jsonschema 12:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:22:28 INFO - Downloading/unpacking functools32 (from jsonschema) 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:28 INFO - Downloading functools32-3.2.3-2.tar.gz 12:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:22:28 INFO - warning: no files found matching '*.txt' 12:22:28 INFO - no previously-included directories found matching 'build' 12:22:28 INFO - no previously-included directories found matching 'dist' 12:22:28 INFO - no previously-included directories found matching '.git*' 12:22:28 INFO - Installing collected packages: jsonschema, functools32 12:22:28 INFO - Running setup.py install for functools32 12:22:28 INFO - warning: no files found matching '*.txt' 12:22:28 INFO - no previously-included directories found matching 'build' 12:22:28 INFO - no previously-included directories found matching 'dist' 12:22:28 INFO - no previously-included directories found matching '.git*' 12:22:29 INFO - Successfully installed jsonschema functools32 12:22:29 INFO - Cleaning up... 12:22:29 INFO - Return code: 0 12:22:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:29 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 0x106b97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ba7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bdadc2ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, '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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:22:29 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 12:22:29 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 12:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:29 INFO - 'HOME': '/Users/cltbld', 12:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:29 INFO - 'LOGNAME': 'cltbld', 12:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:29 INFO - 'MOZ_NO_REMOTE': '1', 12:22:29 INFO - 'NO_EM_RESTART': '1', 12:22:29 INFO - 'PAGER': '/bin/cat', 12:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:29 INFO - 'PWD': '/builds/slave/test', 12:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:29 INFO - 'SHELL': '/bin/bash', 12:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:22:29 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:29 INFO - 'USER': 'cltbld', 12:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:29 INFO - 'XPC_FLAGS': '0x0', 12:22:29 INFO - 'XPC_SERVICE_NAME': '0', 12:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:29 INFO - Downloading/unpacking flake8 12:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:22:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:22:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:22:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:33 INFO - no previously-included directories found matching 'docs/_build' 12:22:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:22:33 INFO - Running setup.py install for pep8 12:22:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:33 INFO - no previously-included directories found matching 'docs/_build' 12:22:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:22:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:22:33 INFO - Cleaning up... 12:22:33 INFO - Return code: 0 12:22:33 INFO - Running post-action listener: _resource_record_post_action 12:22:33 INFO - Running post-action listener: _start_resource_monitoring 12:22:33 INFO - Starting resource monitoring. 12:22:33 INFO - ##### 12:22:33 INFO - ##### Running install step. 12:22:33 INFO - ##### 12:22:33 INFO - Running pre-action listener: _resource_record_pre_action 12:22:33 INFO - Running main action method: install 12:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:34 INFO - Reading from file tmpfile_stdout 12:22:34 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:34 INFO - Reading from file tmpfile_stdout 12:22:34 INFO - Output received: 12:22:34 INFO - Usage: mozinstall [options] installer 12:22:34 INFO - Options: 12:22:34 INFO - -h, --help show this help message and exit 12:22:34 INFO - -d DEST, --destination=DEST 12:22:34 INFO - Directory to install application into. [default: 12:22:34 INFO - "/builds/slave/test"] 12:22:34 INFO - --app=APP Application being installed. [default: firefox] 12:22:34 INFO - mkdir: /builds/slave/test/build/application 12:22:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:23:08 INFO - Reading from file tmpfile_stdout 12:23:08 INFO - Output received: 12:23:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:23:08 INFO - Running post-action listener: _resource_record_post_action 12:23:08 INFO - ##### 12:23:08 INFO - ##### Running run-tests step. 12:23:08 INFO - ##### 12:23:08 INFO - Running pre-action listener: _resource_record_pre_action 12:23:08 INFO - Running main action method: run_tests 12:23:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:08 INFO - 2016-04-19 12:23:08.580 screenresolution[1907:9860] starting screenresolution argv=screenresolution get 12:23:08 INFO - 2016-04-19 12:23:08.583 screenresolution[1907:9860] Display 0: 1600x1200x32@60 12:23:08 INFO - 2016-04-19 12:23:08.588 screenresolution[1908:9867] starting screenresolution argv=screenresolution list 12:23:08 INFO - Available Modes on Display 0 12:23:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:08 INFO - Intel Iris: 12:23:08 INFO - Chipset Model: Intel Iris 12:23:08 INFO - Type: GPU 12:23:08 INFO - Bus: Built-In 12:23:08 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:08 INFO - Vendor: Intel (0x8086) 12:23:08 INFO - Device ID: 0x0a2e 12:23:08 INFO - Revision ID: 0x0009 12:23:08 INFO - Displays: 12:23:08 INFO - Display: 12:23:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:08 INFO - Display Serial Number: 1600x1200 60 12:23:08 INFO - Main Display: Yes 12:23:08 INFO - Mirror: Off 12:23:08 INFO - Online: Yes 12:23:08 INFO - Rotation: Supported 12:23:08 INFO - Return code: 0 12:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:08 INFO - Python 2.7.3 12:23:08 INFO - Return code: 0 12:23:08 INFO - grabbing minidump binary from tooltool 12:23:08 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')]} 12:23:08 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 0x106a36e00>, '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 0x10685fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a31b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:08 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 12:23:08 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 12:23:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:08 INFO - Return code: 0 12:23:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:23:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:23:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:23:10 INFO - Return code: 0 12:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2CmbRBSXJl/Render', 12:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:10 INFO - 'HOME': '/Users/cltbld', 12:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:10 INFO - 'LOGNAME': 'cltbld', 12:23:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:10 INFO - 'MOZ_NO_REMOTE': '1', 12:23:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:10 INFO - 'NO_EM_RESTART': '1', 12:23:10 INFO - 'PAGER': '/bin/cat', 12:23:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:10 INFO - 'PWD': '/builds/slave/test', 12:23:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:10 INFO - 'SHELL': '/bin/bash', 12:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8F8dT7eoQ0/Listeners', 12:23:10 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:10 INFO - 'USER': 'cltbld', 12:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:10 INFO - 'XPC_FLAGS': '0x0', 12:23:10 INFO - 'XPC_SERVICE_NAME': '0', 12:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:23:10 INFO - mozversion application_buildid: 20160419102742 12:23:10 INFO - mozversion application_changeset: 3b3c1809c8be95512093a3cf19d1a35e99ad145c 12:23:10 INFO - mozversion application_display_name: Nightly 12:23:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:10 INFO - mozversion application_name: Firefox 12:23:10 INFO - mozversion application_remotingname: firefox 12:23:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:23:10 INFO - mozversion application_vendor: Mozilla 12:23:10 INFO - mozversion application_version: 48.0a1 12:23:10 INFO - mozversion platform_buildid: 20160419102742 12:23:10 INFO - mozversion platform_changeset: 3b3c1809c8be95512093a3cf19d1a35e99ad145c 12:23:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:23:10 INFO - mozversion platform_version: 48.0a1 12:23:10 INFO - using testdate: 1461093790 12:23:10 INFO - actual date: 1461093790 12:23:10 INFO - starting webserver on 'localhost:49459' 12:23:10 INFO - SUITE-START | Running 2 tests 12:23:10 INFO - TEST-START | dromaeo_css 12:23:10 INFO - operating with platform_type : mac_ 12:23:10 INFO - Initialising browser for dromaeo_css test... 12:23:10 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpseXLxd/profile http://localhost:49459/getInfo.html) 12:23:12 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 12:23:12 INFO - PROCESS | 1926 | colorDepth:24 12:23:12 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 12:23:12 INFO - PROCESS | 1926 | __metrics 12:23:12 INFO - PROCESS | 1926 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:23:12 ERROR - PROCESS | 1926 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:23:13 INFO - TEST-INFO | 1926: exit 0 12:23:13 INFO - Browser initialized. 12:23:13 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:13 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpseXLxd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:25 INFO - PROCESS | 1928 | RSS: Main: 237318144 12:23:25 INFO - PROCESS | 1928 | 12:24:17 INFO - PROCESS | 1928 | 1461093857416 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] 12:26:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49459/tests/dromaeo/cssquery-ext.html) 12:26:26 INFO - PROCESS | 1928 | RSS: Main: 351522816 12:26:26 INFO - PROCESS | 1928 | 12:29:29 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-ext.html (next: http://localhost:49459/tests/dromaeo/cssquery-jquery.html) 12:29:30 INFO - PROCESS | 1928 | RSS: Main: 374968320 12:29:30 INFO - PROCESS | 1928 | 12:32:32 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49459/tests/dromaeo/cssquery-mootools.html) 12:32:32 INFO - PROCESS | 1928 | RSS: Main: 376094720 12:32:32 INFO - PROCESS | 1928 | 12:35:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49459/tests/dromaeo/cssquery-prototype.html) 12:35:35 INFO - PROCESS | 1928 | RSS: Main: 375189504 12:35:35 INFO - PROCESS | 1928 | 12:38:39 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49459/tests/dromaeo/cssquery-yui.html) 12:38:39 INFO - PROCESS | 1928 | RSS: Main: 342032384 12:38:39 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49459/tests/dromaeo/cssquery-yui.html (next: http://localhost:49459/tests/dromaeo/cssquery-dojo.html) 12:41:42 INFO - PROCESS | 1928 | RSS: Main: 385363968 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | __start_tp_report 12:41:42 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 12:41:42 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 12:41:42 INFO - PROCESS | 1928 | |i|pagename|runs| 12:41:42 INFO - PROCESS | 1928 | |0;dojo.html;2329.4292898239933;2843.8720257588407;2896.724811142942;2899.0288253435097;2900.695426980167;4473.1501014807245;4599.3560901473775;4614.676972611915;4632.00411911439;4701.882452938674;4525.298578720295;4604.286335617976;4636.976815115928;4652;4664.346991421204;4243.9178009607585;4288.742675439484;4289.6997210195295;4317.848875289365;4362.738235705852;3579.6241394653575;3895.4351619015224;4329.199098166833;4353.891152721176;4360.127974405116;381.81820535089116;4334.241507736134;4339.2406328892475;4340.8697739067875;4413.86758397247;4213.494380674308;4241.597048280409;4283.357496375537;4317.15815415994;4365.759883206422;4254.7872606369865;4286.121345124242;4289.935650965238;4322.351647252905;4330.913381732347;4352.368906508538;4538.364628951949;4554.111948170108;4562.771861406916;4568.132054909556;5055.797768089272;5119.744012799345;5130.050940576006;5167.819126330598;5174.922376164361;5123.2315152727015;5129.333186685707;5166.819161329336;5174.922376164361;5239.345081864767;4721.315409265671;4815.49437309081;4867.537583929521;4869.561739443467;4872.293517439987;5168.198929165986;5280.445553216934;5329.786808527616;5347.572194224453;5347.625666203328;4133.607307305801;5021.5229553192385;5047.343845300087;5057.140286151363;5094.312267843794;5023.572996295356;5136.512031357015;5147.897042059138;5149.407818100892;5160.587153027749;4918.139325618003;5114.130597798383;5140.871478213;5143.819966301162;5164.457731938168;5077.669951453144;5099.949000509948;5110.770015349244;5111.488851114858;5132.358455193101;4074.144429669743;4134.751914885056;4180.435641188462;4203.159368126387;4229.471316085489;3655.323765103464;3718.3492888744363;3738.9065273368383;3750.9249815003545;3811.923761524754;4517.006258623098;4537.546245375502;4545.499995000548;4552.453705555288;4580.106879158532;4995.825146119869;5144.61415393847;5163.070647283525;5178.508041736029;5209.583233341324;4434.0245146068455;4524;4534.093181363741;4558.156741002925;4569.748663823521;4528.366028755977;4533.206688829508;4547.090581883636;4573.839915602938;4575.725456472623;5192.221166824931;5332.573394128538;5359.383670877821;5397.055515284809;5397.946020539823;5107.591392688501;5129.820456284012;5134.5635620972635;5141.948580514147;5153.510416510426;5292.603054770984;5319.893602128013;5338.225957236102;5353;5353.304070470814;5118.206677964921;5135.537801597874;5171.120909445479;5174.948250517597;5194.3247377840635;5180.7409629519125;5192.610554208374;5193.11717008102;5197.298364720866;5236.031334203185;4848.32123502717;5019.924701129559;5031.84904452867;5047.924281135859;5048.848534543969;4265.019045619587;4953.18272485036;4954.1825598775795;4972.726500042532;5057.797688092435;4907.877303067452;4934.18585933317;4935.087008903364;4941.629377796751;4985.027919555652;4868.48880867518;5020.949790502048;5022.4977502249485;5044.697318160922;5076.390833100052;4794.7842347093765;4822.903541929071;4870.829520966749;4930.260460930889;4944.629152813625 12:41:42 INFO - PROCESS | 1928 | |1;ext.html;449.6402877697842;459.48308153327486;471.55202557570294;501.2030870915244;510.3136281701112;29737.70262297379;36119;37342.62657373429;37379.06552336193;37442;40731;41882.7905860471;41941;42100.78949605255;42179;8087.433879628399;9121.635134594624;9366.531673416335;9373.718788436357;9407.52962351883;8212.260896519312;10799.730006749855;11140.944295278512;11142.721431964224;11173.776524469546;10842.783144337109;10960.342379457219;10967.561297548089;10972.945135274313;11001.889981100207;8115.837683246361;10755.946220268926;10759.677209683721;10894.891051089506;11079.947404996512;10806.891931080667;10922.672319830339;10943.945280273547;10948.124150068055;10973.725656858562;36411;37098.62901371006;37105;37107.62892371069;37199;3237.43344914639;3626.7642603230706;3646.08847788053;3699.0567405311745;3839.520059992501;27462;27591.172264832087;27921.16236512908;27960.161195163975;28426.147215583565;2587.8964841406323;2646.8941242350284;2658.548046832024;2664.1341563991587;2725.427660191362;476087;476227;476828;478686;480473;56491.43508564944;56872;57002;57122.42877571254;57123;60077.399226008056;61676.69161654163;63803.36196638067;63884.68057659775;63921.72156556843;15735.370585176694;15810;16019.359225630844;16023;16039.679206415807;11348.829767553494;11667.183297169116;12231.816522752166;12296.323702196287;12720.745585088247;17609.471715848544;17648.470545883643;17653.20560574777;17653.646927061385;17660.823391766175;7291.573585917238;7553.584552849646;7599.820098945558;7621.161672216052;7645.273698998586;801.5711594297023;921.8386782313069;926.7173512078881;1014.7919676466307;1073.7959787640323;28043.859780700965;28361.37918626233;28472.430551389272;28784.39623666886;29036.564451533242;996.2627655534852;1022.5602990714065;1087.8857719939292;1097.3415950429837;1101.895319944604;14663.633409164857;14681.559553213421;14810.111393316436;15004.174770387515;15043.699126017418;616648;618810;624188;624935;627530;56176.43823561712;56836.14745778817;56919;57036.71481642565;57073.429265706814;1249.743802520481;1376.126159888478;1416.5113036002563;1421.175718083509;1425.8075159853288;27031.59452608173;27148.32129196707;27764.583531246644;27900.860495698205;27903.860480697465;33451.49822752612;42449.93875153146;42975.9256018602;43119.56880431156;43181;298106;328981;331869;332053;332578;22039;24203.75796242015;24915.127970520945;25124.24627261185;25200.747992520573;40033.79983100066;40443.79778101091;42236.36645450259;42493.362599560416;42792.14415711606;33819.4927076099;34645.653543465254;34865;35172.44114788914;35814.820925895205;34275.4858677125;34471.82764086164;35015;35096.29807403889;35151.12122196965;34739.957801266006;34982.30035399328;34987.65012349946;35809.64190358063;36654.8167259162;29270;29846.25384365408;29910;30029.54955675623;30063.947761828233;20895.686564701846;22618;22745.658815117455;23083.538329232986;23141.958611862843 12:41:42 INFO - PROCESS | 1928 | |2;jquery.html;363.16834449111536;365.19838953497066;369.6986955631162;377.7110510459497;379.11476701901057;19277.325293614726;19450.319238826643;19811.20755169795;19986.600267994632;20008.799472031653;18835.905820470914;19110;19983.803401285746;20115.19539218433;20186.889721065334;4621;4953.504649535044;5135;5164.147915593941;5179.145441002249;5852.268466441695;5872.853178670524;5894.764209431618;5920.97039514804;5922.022866227067;5323.387810401795;5753.942460575404;5892.793752218673;5895.086261629454;5897.498712609433;5209.036328279242;5330.7334633268565;5611.3266408031095;5664.291963504562;5691.971540142315;5222.556082732935;5453.890922181574;5610.579206559483;5618.0449323614675;5625.38120806711;12562.748745025048;12687.238765674088;12837.422315995802;14016;23393.649095263583;1929.421173647914;1946.2701486942397;2015.748031496063;2040.5204776877422;2087.8538502304846;8103.635336409876;8904;9513.714588562354;9560.996095409952;9774.51127443625;1421.211227768588;1593.7051645445513;1633.5099470158905;1648.826873178323;1706.8719846011475;110288;117430;118872;119342.40328798388;121520;9539.856902146474;10909.127269818555;11089;11117.610883619196;11183.888161118284;14609.415623374945;14612.634684132981;14647;14662.633434164018;14682;6789.592624442451;6832.555883867532;8055.7583272501915;8364.790880228044;8370.874436883452;8056.5971701415865;8069.31410830086;8080.636371363303;8169.30560902318;8179.182081791892;4385.451818522684;4410.713303635253;4425.35832304314;4430.224710675683;4507.301368287921;1501.6546194375137;1655.2716804605934;1875.8780679256079;1879.332836842901;1902;772.6214155063979;816.8202995341015;840.7771940435789;911.0343036381457;933.8926023507248;13155.473781048842;19747.901260493607;20228.79771202269;20345.79654203439;20353.389398317777;862.7023676831483;900.4912224593083;924.6255266617031;931.7996630724426;973.9919183645035;9279.443233406017;9848.799140279294;10545.630902918361;10637.521311540999;10686.89313106859;229168;231865;232569;233262;233302;78228.21771782324;78641;119280;121621;126172.34867309521;796.4663675337538;879.9516026618469;905.7871400220943;909.1681111782777;910.9726708198765;13133.474661013452;16397.42609008679;17756.91121544384;18005.909970450062;18074.915505069737;38475.807620961714;42120;45393.638190854326;47151.349702760235;47746.28380574364;68032.63934721179;70412;70968.29031709823;72713.27286727064;73791.63104184445;6649.335066493312;7187.2453392393045;7305.086864141982;7513.962430188033;7867.763967080997;10161.339512931636;11775.470103845177;11977.981871541017;12449.128561000643;12466.1273710843;10341.534630941474;10427.667237822257;10505.579776808841;10795.401115571025;10933.726656833325;10407.011333923265;10527.157827373638;10694.304870183412;10985.340879547253;11058.557657693602;10830.079443247412;10864.945675271572;10895.673129806119;10987.835182472429;11246.66260012201;7253.891191632024;7264.23725508814;7365.7790266292095;7398.778036658909;7547.849043019219;8853.867191992254;10834.404107774148;10835.458227088457;11089.611863584736;11153.163512736739 12:41:42 INFO - PROCESS | 1928 | |3;mootools.html;1329.8670132986697;1493.3503925792281;1507.2463768115945;1588.6822635472909;1861.6369807887468;6313.905291420632;8318.085010648823;8552.102029286929;8570;8605.053444121142;7823.139454659983;8219.835603287946;8401.537915414665;8404.66381344747;8412.789680257996;2264.6829443877828;2303.6429353450244;2306.5963456395148;2309.3764683535437;2311.0986715181093;2271.5343354612264;2300.3559003479054;2306.2504685977124;2311.0293676655847;2323.7327707313707;2282.6233671444193;2294.369048511656;2305.1470955746313;2321.1875843454823;2325.500017496238;2265.9660105098437;2279.566882292359;2285.988570057147;2289.8969546370454;2291.9885400572975;2206.227820262895;2215.280033988944;2215.656573231152;2220.378294077661;2220.5669894370603;8178;8408.957955210246;8427.452215605967;8489.915100848973;8499.830003399897;4905.8773530661665;5058.4435712071645;5066.923996140061;5071.061853557067;5085.618578606583;3282.770206085575;3297.868085276586;3374.8481318340732;3388.1868351595685;3388.7797293176113;978.3542861711308;985.1724551376806;1000.0699349604864;1005.3415013166388;1020.1991436722137;10068.345557538736;10111.977604479134;10870.673879783619;11153.721156970978;11173.83239251412;3430;3606.6032736398975;4047.9595204048173;4201.747895126241;4240.597143271384;7974.122846486882;8090.474119182234;8100.554469504234;8114.431989760778;8114.918850811534;4929.0881186979805;4942.159832828428;4942.4316203637045;4960.627952903475;4973.005398920231;4233.767142807175;4410.625096866802;4570.017446249072;4744.525547445228;4747.0505898820375;3537.7346698997676;3568.839402226906;3642.7996460194413;3658.835352409148;3671.2657468506404;1119.5521791283552;1142.1034487927104;1142.5601143559788;1156.5200441816553;1160.9129315301386;530.7160669042121;586.0915580849751;706.2901783707443;785.7249962513189;801.6873419366452;5224.555912747459;5417.51242388187;5461.726913654302;5490.69801160932;5665.3201615806365;511.7697036333665;527.9994410591906;534.5429657642737;535.6411204492999;654.3358491131505;3064.923376915595;3505.5968563615;3735.701143908481;3777.8866634001024;3783.451399547105;9895.80208395828;9943.850842237372;10277.17782577392;10428.687139385831;10437.425941573287;10179.083882450466;10181.592736290613;10214.029667181605;10344.5344959477;10351.896481035245;7511.361534269539;7612.276833700789;7775.650095745592;7910.644021019068;7941.64262608184;5471.316085489314;5612.102063669793;5614;5646.576506761927;5750.6262092963825;9368.34421590482;9370.437773733598;9505.239580833379;9593.616255349987;9796.608135674493;9132.22376098039;9301.395409298373;9742.95128524353;9765.974572670051;10028.849567256351;7076.540024898365;7159.463040271896;7252.746153884589;7306.525075870051;7315.122185337794;7728.884066739116;7867.173946735739;7875.054993400829;7877.015373078365;7880.487768295043;5772.544945026909;6960.269171737097;6996.545224560387;7221.53060051095;7266.927330726625;5810.680412577349;6947.7220911162985;6960.47796415281;7057.082579264663;7138.714451421885;6487.708053137526;6614.272430032596;6635.303293154342;6660.833479162866;6671.366220209073;4534.886627834198;4574.797641650791;4651.116287905287;4656.348111264493;4700.130475861976;4533.750643714628;4600.240960241523;4651.790669419816;4670.5329467053025;4835.129676658235 12:41:42 INFO - PROCESS | 1928 | |4;prototype.html;279.9272189230801;288.6262290334017;295.06464507511186;299.08927316321797;301.82192506421205;4200.726952748069;4289.678274129441;4324.329728892023;4338.240807858624;4488.730676159425;3810.6379893910034;4128.504579450462;4327.632151267146;4423.778811059442;4501.909961800762;2786.4148528808973;2787.6236708044457;3098.9225269368276;3300.257442075526;3421.0250078727568;2776.9166924992182;3106.922326941822;3164.5727826743323;3201.967980320202;3450.46517789743;2935.5890175375357;3386.3735208986295;3392.542006829071;3434.2959693262947;3439;3361.1933136047296;3371.275175837207;3381.8839782871837;3391.9491207631904;3402.336544373848;3007.293286077792;3013.050888969967;3022.3804120155314;3023.546468029791;3026.9545956810666;3921.29416704993;3948.8617898373705;4008.498937632796;4010.0977280112033;4053.169100334454;3897.941530877039;3944.3886197639413;3951.288768021755;3962.1085255817497;3962.8811135665687;4249.086446414005;4257;4589.954100458986;4657.743824089674;4678.766061696935;3391.2708767614836;3402.1494626343415;3405.5062016007787;3405.897823065312;3412.5051867479324;3761.6990640748672;4858.562729354339;4862.319275301461;4872.756362181876;4879.707217566922;2750.642416485884;3320.8007519548905;3342.5487559179687;3348.079278198515;3356.7482438817187;3371.713404360608;3378.8986330410125;3383.2725963917874;3385.5768028996376;3406.4500652203187;3854.4989151410136;3862.9034274143373;3864.4976153099915;3879.96120038796;3883.7475564088245;4536.115457485825;4598.839040633562;4666.113438446684;4668.883277918011;4677.789499472532;954.6706386296717;1044.7179261599326;1118.8531754951077;1142.5144313666658;1146.587228597721;758.3440324119707;776.3556248313886;776.4642396746227;819.1521774963004;827.6978902700471;4047.372657238133;4054.2094291613444;4066.6136717012428;4113.526944401432;4113.650339721097;4223.324268117087;4357.716748411343;4383.882110061913;4738.976305118453;4766.237402015661;3862.2854771867296;4090.795460226977;4133.338665813455;4201.810918508674;4273;2517;2533.7331334332835;2551.617257411366;2558.85926274053;2561.142017424184;5126.615503837227;5136.332276804066;5147.408048074444;5186.662866913714;5204.7918083276245;3641.8543258269374;4596.8161273548685;4857.465678775332;4980.5019498049905;5013.498650134958;4193.002003567464;4522.128448435267;4762.6189904808225;5049.267856160836;5117.334746483008;4810;4816.447282940047;4822.95177048225;4834.6374021947795;4858.562729354375;4180.979095104505;4568.269076947672;4819.698681356084;4821.5178482152905;4833.758312084452;5046.4701206374275;5092.261622064854;5094.541491265655;5129.461406552258;5138.588912887035;3274.6561611030497;3318.2533929865585;3322.302316513559;3323.26888084621;3333.3999480405323;4373.781310934377;4783.402074740658;4783.904321913612;4803.159447096674;4810.63920205993;4605.148047611203;4645.02454484562;4674.088552732184;4697.224957881911;4712.693674911257;4244.830206791818;4362.345648152803;4564.42944631921;4668.486466488752;4668.92996605058;3809.1619843634357;4190.224808410454;4577.176108300538;4593.586577208067;4618.30725391194;4378.2119218540965;4382.868513944587;4579.793909274024;4628.259478483426;4653.324338505306;3969.900752481211;4480.686351955332;4507.008458139204;4532.660050496292;4596.954030459653 12:41:42 INFO - PROCESS | 1928 | |5;yui.html;613.2395829171827;697.2434908124684;706.1314583062832;712.4941291682902;725.6480606905651;14156.07985480943;14594.927025364885;14605.488807891727;14610.26948652567;14630.195339256337;12931.612051638442;13223.471061157567;13448.462061517552;13449.932750336258;13452.730945381087;893.2853717026385;914.1863741270241;923.833709932212;924.958376873039;925.8611208318772;904.2630256341093;906.1391677905983;909.9545022748869;911.1389736698823;912.2337236721153;936.967206147785;951.0869565217365;962.9181519570844;965.9903400966006;966.8888077871029;963.5471328475641;968.2060710217625;968.8885778135534;969.2294625772528;969.8690676758617;725.1914115760941;725.3580581185636;725.804032911111;727.5016613619688;727.8071311102563;12508.249505029728;12779.808302875465;12831.743365132645;12834.422450989729;12865.485380584765;10849.728756781144;10940.507677154546;11032.704301935117;11261.605843795509;11541.365224912626;8911.420757650732;9189.954050229706;9193.586288617029;9237.983821779599;9511.381760185566;3266.3630592034565;3323.401787678217;3377.2738861144726;3382.475716263983;3406.67636574525;76249.23750762477;76507.61746191289;77439.99999999943;80377.19622803814;80445.79331310035;8035.839283214303;8054.23484768946;8126.024877014796;8148.185181481805;8391.748247552583;18383.90808045951;18385.26458941654;18389.264429422674;18442;18451.16969736365;7204.711811527585;7288.890666640004;7409.777706668809;7447.180810110884;7487.775366739006;3169.7147256747003;3747.606501317377;3781.6029316921877;3787.4886890270013;3791.521306690393;3288.473844184919;3395.575553055868;3411.573553305837;3414.6926776590226;3418;592.762894842058;594.191899017336;601.290477236856;603.3996173807091;617.2438762515882;530.4138926486178;539.840746979642;550.6751016900048;555.3779766661365;567.4495739133034;7422;7594.848103037908;7642.312191902755;7708.49894756839;7872.488288261245;459.2422502870291;519.8064169205945;520.2040877457496;526.5261264861579;534.9197620356978;3542.574891013095;3560.412531932254;3565.3404120237838;3567.143885467521;3649.379605467077;87779;89257.55371223231;89354.10645893587;89822.75443113975;90142.64786028214;55603.72198139064;65768;74968;75644.62177689077;75822;65777.85776058075;67006.32993670001;67643.66178169078;68416.63166736637;69500.6524967382;7832.530048197126;7968.0836703779805;7978.680852766057;7995.920040799518;8117.1962047136585;40959.795201024994;41014;41050.79474602608;41122.794386029076;41480.79259603683;59464.70267648634;60349;60448.79102418015;60516.78966420735;60526.394736052076;10997.725056873642;11036.558537658404;11054.281471704313;11355.030293367465;11363.204575679623;11216.495257713586;11823.763524729286;11890.881091188978;12058.276503409823;12159.635210943687;9683.564239609375;10992.725181870517;11689.649310520697;11719.121065919919;11796.410179491162;11076.778464430827;11077.712756396122;11272.323660580392;11465.828012579985;12033.097517686034;9263.583138758906;10652.84020739705;10781.9460902695;10862.837057443987;10876.465148826821;8922.288778368149;9052.094790520896;9125.543722813965;9196.54017299119;9344.672936447192;8902.955485222532;9298.721038368589;9300.255979521487;9351.158395744416;9416.670416535388 12:41:42 INFO - PROCESS | 1928 | __end_tp_report 12:41:42 INFO - PROCESS | 1928 | __start_cc_report 12:41:42 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,1211 12:41:42 INFO - PROCESS | 1928 | __end_cc_report 12:41:42 INFO - PROCESS | 1928 | __startTimestamp1461094902893__endTimestamp 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | ------- Summary: start ------- 12:41:42 INFO - PROCESS | 1928 | Number of tests: 6 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:4701.64 Median:4907.88 stddev:639.57 (13.0%) stddev-sans-first:613.86 12:41:42 INFO - PROCESS | 1928 | Values: 2329.4 2843.9 2896.7 2899.0 2900.7 4473.2 4599.4 4614.7 4632.0 4701.9 4525.3 4604.3 4637.0 4652.0 4664.3 4243.9 4288.7 4289.7 4317.8 4362.7 3579.6 3895.4 4329.2 4353.9 4360.1 381.8 4334.2 4339.2 4340.9 4413.9 4213.5 4241.6 4283.4 4317.2 4365.8 4254.8 4286.1 4289.9 4322.4 4330.9 4352.4 4538.4 4554.1 4562.8 4568.1 5055.8 5119.7 5130.1 5167.8 5174.9 5123.2 5129.3 5166.8 5174.9 5239.3 4721.3 4815.5 4867.5 4869.6 4872.3 5168.2 5280.4 5329.8 5347.6 5347.6 4133.6 5021.5 5047.3 5057.1 5094.3 5023.6 5136.5 5147.9 5149.4 5160.6 4918.1 5114.1 5140.9 5143.8 5164.5 5077.7 5099.9 5110.8 5111.5 5132.4 4074.1 4134.8 4180.4 4203.2 4229.5 3655.3 3718.3 3738.9 3750.9 3811.9 4517.0 4537.5 4545.5 4552.5 4580.1 4995.8 5144.6 5163.1 5178.5 5209.6 4434.0 4524.0 4534.1 4558.2 4569.7 4528.4 4533.2 4547.1 4573.8 4575.7 5192.2 5332.6 5359.4 5397.1 5397.9 5107.6 5129.8 5134.6 5141.9 5153.5 5292.6 5319.9 5338.2 5353.0 5353.3 5118.2 5135.5 5171.1 5174.9 5194.3 5180.7 5192.6 5193.1 5197.3 5236.0 4848.3 5019.9 5031.8 5047.9 5048.8 4265.0 4953.2 4954.2 4972.7 5057.8 4907.9 4934.2 4935.1 4941.6 4985.0 4868.5 5020.9 5022.5 5044.7 5076.4 4794.8 4822.9 4870.8 4930.3 4944.6 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:60937.95 Median:183014.75 stddev:131101.52 (71.6%) stddev-sans-first:131390.63 12:41:42 INFO - PROCESS | 1928 | Values: 449.6 459.5 471.6 501.2 510.3 29737.7 36119.0 37342.6 37379.1 37442.0 40731.0 41882.8 41941.0 42100.8 42179.0 8087.4 9121.6 9366.5 9373.7 9407.5 8212.3 10799.7 11140.9 11142.7 11173.8 10842.8 10960.3 10967.6 10972.9 11001.9 8115.8 10755.9 10759.7 10894.9 11079.9 10806.9 10922.7 10943.9 10948.1 10973.7 36411.0 37098.6 37105.0 37107.6 37199.0 3237.4 3626.8 3646.1 3699.1 3839.5 27462.0 27591.2 27921.2 27960.2 28426.1 2587.9 2646.9 2658.5 2664.1 2725.4 476087.0 476227.0 476828.0 478686.0 480473.0 56491.4 56872.0 57002.0 57122.4 57123.0 60077.4 61676.7 63803.4 63884.7 63921.7 15735.4 15810.0 16019.4 16023.0 16039.7 11348.8 11667.2 12231.8 12296.3 12720.7 17609.5 17648.5 17653.2 17653.6 17660.8 7291.6 7553.6 7599.8 7621.2 7645.3 801.6 921.8 926.7 1014.8 1073.8 28043.9 28361.4 28472.4 28784.4 29036.6 996.3 1022.6 1087.9 1097.3 1101.9 14663.6 14681.6 14810.1 15004.2 15043.7 616648.0 618810.0 624188.0 624935.0 627530.0 56176.4 56836.1 56919.0 57036.7 57073.4 1249.7 1376.1 1416.5 1421.2 1425.8 27031.6 27148.3 27764.6 27900.9 27903.9 33451.5 42449.9 42975.9 43119.6 43181.0 298106.0 328981.0 331869.0 332053.0 332578.0 22039.0 24203.8 24915.1 25124.2 25200.7 40033.8 40443.8 42236.4 42493.4 42792.1 33819.5 34645.7 34865.0 35172.4 35814.8 34275.5 34471.8 35015.0 35096.3 35151.1 34740.0 34982.3 34987.7 35809.6 36654.8 29270.0 29846.3 29910.0 30029.5 30063.9 20895.7 22618.0 22745.7 23083.5 23142.0 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:23053.32 Median:367.45 stddev:44285.30 (12052.1%) stddev-sans-first:44376.77 12:41:42 INFO - PROCESS | 1928 | Values: 363.2 365.2 369.7 377.7 379.1 19277.3 19450.3 19811.2 19986.6 20008.8 18835.9 19110.0 19983.8 20115.2 20186.9 4621.0 4953.5 5135.0 5164.1 5179.1 5852.3 5872.9 5894.8 5921.0 5922.0 5323.4 5753.9 5892.8 5895.1 5897.5 5209.0 5330.7 5611.3 5664.3 5692.0 5222.6 5453.9 5610.6 5618.0 5625.4 12562.7 12687.2 12837.4 14016.0 23393.6 1929.4 1946.3 2015.7 2040.5 2087.9 8103.6 8904.0 9513.7 9561.0 9774.5 1421.2 1593.7 1633.5 1648.8 1706.9 110288.0 117430.0 118872.0 119342.4 121520.0 9539.9 10909.1 11089.0 11117.6 11183.9 14609.4 14612.6 14647.0 14662.6 14682.0 6789.6 6832.6 8055.8 8364.8 8370.9 8056.6 8069.3 8080.6 8169.3 8179.2 4385.5 4410.7 4425.4 4430.2 4507.3 1501.7 1655.3 1875.9 1879.3 1902.0 772.6 816.8 840.8 911.0 933.9 13155.5 19747.9 20228.8 20345.8 20353.4 862.7 900.5 924.6 931.8 974.0 9279.4 9848.8 10545.6 10637.5 10686.9 229168.0 231865.0 232569.0 233262.0 233302.0 78228.2 78641.0 119280.0 121621.0 126172.3 796.5 880.0 905.8 909.2 911.0 13133.5 16397.4 17756.9 18005.9 18074.9 38475.8 42120.0 45393.6 47151.3 47746.3 68032.6 70412.0 70968.3 72713.3 73791.6 6649.3 7187.2 7305.1 7514.0 7867.8 10161.3 11775.5 11978.0 12449.1 12466.1 10341.5 10427.7 10505.6 10795.4 10933.7 10407.0 10527.2 10694.3 10985.3 11058.6 10830.1 10864.9 10895.7 10987.8 11246.7 7253.9 7264.2 7365.8 7398.8 7547.8 8853.9 10834.4 10835.5 11089.6 11153.2 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5326.98 Median:4951.53 stddev:3011.53 (60.8%) stddev-sans-first:3005.00 12:41:42 INFO - PROCESS | 1928 | Values: 1329.9 1493.4 1507.2 1588.7 1861.6 6313.9 8318.1 8552.1 8570.0 8605.1 7823.1 8219.8 8401.5 8404.7 8412.8 2264.7 2303.6 2306.6 2309.4 2311.1 2271.5 2300.4 2306.3 2311.0 2323.7 2282.6 2294.4 2305.1 2321.2 2325.5 2266.0 2279.6 2286.0 2289.9 2292.0 2206.2 2215.3 2215.7 2220.4 2220.6 8178.0 8409.0 8427.5 8489.9 8499.8 4905.9 5058.4 5066.9 5071.1 5085.6 3282.8 3297.9 3374.8 3388.2 3388.8 978.4 985.2 1000.1 1005.3 1020.2 10068.3 10112.0 10870.7 11153.7 11173.8 3430.0 3606.6 4048.0 4201.7 4240.6 7974.1 8090.5 8100.6 8114.4 8114.9 4929.1 4942.2 4942.4 4960.6 4973.0 4233.8 4410.6 4570.0 4744.5 4747.1 3537.7 3568.8 3642.8 3658.8 3671.3 1119.6 1142.1 1142.6 1156.5 1160.9 530.7 586.1 706.3 785.7 801.7 5224.6 5417.5 5461.7 5490.7 5665.3 511.8 528.0 534.5 535.6 654.3 3064.9 3505.6 3735.7 3777.9 3783.5 9895.8 9943.9 10277.2 10428.7 10437.4 10179.1 10181.6 10214.0 10344.5 10351.9 7511.4 7612.3 7775.7 7910.6 7941.6 5471.3 5612.1 5614.0 5646.6 5750.6 9368.3 9370.4 9505.2 9593.6 9796.6 9132.2 9301.4 9743.0 9766.0 10028.8 7076.5 7159.5 7252.7 7306.5 7315.1 7728.9 7867.2 7875.1 7877.0 7880.5 5772.5 6960.3 6996.5 7221.5 7266.9 5810.7 6947.7 6960.5 7057.1 7138.7 6487.7 6614.3 6635.3 6660.8 6671.4 4534.9 4574.8 4651.1 4656.3 4700.1 4533.8 4600.2 4651.8 4670.5 4835.1 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:3806.97 Median:4196.86 stddev:1153.66 (27.5%) stddev-sans-first:1126.11 12:41:42 INFO - PROCESS | 1928 | Values: 279.9 288.6 295.1 299.1 301.8 4200.7 4289.7 4324.3 4338.2 4488.7 3810.6 4128.5 4327.6 4423.8 4501.9 2786.4 2787.6 3098.9 3300.3 3421.0 2776.9 3106.9 3164.6 3202.0 3450.5 2935.6 3386.4 3392.5 3434.3 3439.0 3361.2 3371.3 3381.9 3391.9 3402.3 3007.3 3013.1 3022.4 3023.5 3027.0 3921.3 3948.9 4008.5 4010.1 4053.2 3897.9 3944.4 3951.3 3962.1 3962.9 4249.1 4257.0 4590.0 4657.7 4678.8 3391.3 3402.1 3405.5 3405.9 3412.5 3761.7 4858.6 4862.3 4872.8 4879.7 2750.6 3320.8 3342.5 3348.1 3356.7 3371.7 3378.9 3383.3 3385.6 3406.5 3854.5 3862.9 3864.5 3880.0 3883.7 4536.1 4598.8 4666.1 4668.9 4677.8 954.7 1044.7 1118.9 1142.5 1146.6 758.3 776.4 776.5 819.2 827.7 4047.4 4054.2 4066.6 4113.5 4113.7 4223.3 4357.7 4383.9 4739.0 4766.2 3862.3 4090.8 4133.3 4201.8 4273.0 2517.0 2533.7 2551.6 2558.9 2561.1 5126.6 5136.3 5147.4 5186.7 5204.8 3641.9 4596.8 4857.5 4980.5 5013.5 4193.0 4522.1 4762.6 5049.3 5117.3 4810.0 4816.4 4823.0 4834.6 4858.6 4181.0 4568.3 4819.7 4821.5 4833.8 5046.5 5092.3 5094.5 5129.5 5138.6 3274.7 3318.3 3322.3 3323.3 3333.4 4373.8 4783.4 4783.9 4803.2 4810.6 4605.1 4645.0 4674.1 4697.2 4712.7 4244.8 4362.3 4564.4 4668.5 4668.9 3809.2 4190.2 4577.2 4593.6 4618.3 4378.2 4382.9 4579.8 4628.3 4653.3 3969.9 4480.7 4507.0 4532.7 4597.0 12:41:42 INFO - PROCESS | 1928 | 12:41:42 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:16994.12 Median:60482.79 stddev:24041.84 (39.7%) stddev-sans-first:24077.82 12:41:42 INFO - PROCESS | 1928 | Values: 613.2 697.2 706.1 712.5 725.6 14156.1 14594.9 14605.5 14610.3 14630.2 12931.6 13223.5 13448.5 13449.9 13452.7 893.3 914.2 923.8 925.0 925.9 904.3 906.1 910.0 911.1 912.2 937.0 951.1 962.9 966.0 966.9 963.5 968.2 968.9 969.2 969.9 725.2 725.4 725.8 727.5 727.8 12508.2 12779.8 12831.7 12834.4 12865.5 10849.7 10940.5 11032.7 11261.6 11541.4 8911.4 9190.0 9193.6 9238.0 9511.4 3266.4 3323.4 3377.3 3382.5 3406.7 76249.2 76507.6 77440.0 80377.2 80445.8 8035.8 8054.2 8126.0 8148.2 8391.7 18383.9 18385.3 18389.3 18442.0 18451.2 7204.7 7288.9 7409.8 7447.2 7487.8 3169.7 3747.6 3781.6 3787.5 3791.5 3288.5 3395.6 3411.6 3414.7 3418.0 592.8 594.2 601.3 603.4 617.2 530.4 539.8 550.7 555.4 567.4 7422.0 7594.8 7642.3 7708.5 7872.5 459.2 519.8 520.2 526.5 534.9 3542.6 3560.4 3565.3 3567.1 3649.4 87779.0 89257.6 89354.1 89822.8 90142.6 55603.7 65768.0 74968.0 75644.6 75822.0 65777.9 67006.3 67643.7 68416.6 69500.7 7832.5 7968.1 7978.7 7995.9 8117.2 40959.8 41014.0 41050.8 41122.8 41480.8 59464.7 60349.0 60448.8 60516.8 60526.4 10997.7 11036.6 11054.3 11355.0 11363.2 11216.5 11823.8 11890.9 12058.3 12159.6 9683.6 10992.7 11689.6 11719.1 11796.4 11076.8 11077.7 11272.3 11465.8 12033.1 9263.6 10652.8 10781.9 10862.8 10876.5 8922.3 9052.1 9125.5 9196.5 9344.7 8903.0 9298.7 9300.3 9351.2 9416.7 12:41:42 INFO - PROCESS | 1928 | -------- Summary: end -------- 12:41:42 INFO - PROCESS | 1928 | 12:41:43 INFO - TEST-INFO | 1928: exit 0 12:41:43 INFO - TEST-OK | dromaeo_css | took 1113133ms 12:41:43 INFO - TEST-START | kraken 12:41:43 INFO - operating with platform_type : mac_ 12:41:43 INFO - Initialising browser for kraken test... 12:41:43 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpI2TD2k/profile http://localhost:49459/getInfo.html) 12:41:45 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 12:41:45 INFO - PROCESS | 1934 | colorDepth:24 12:41:45 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 12:41:45 INFO - PROCESS | 1934 | __metrics 12:41:45 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:41:45 ERROR - PROCESS | 1934 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:41:46 INFO - TEST-INFO | 1934: exit 0 12:41:46 INFO - Browser initialized. 12:41:46 INFO - Running cycle 1/1 for kraken test... 12:41:46 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpI2TD2k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:58 INFO - PROCESS | 1936 | RSS: Main: 237367296 12:41:58 INFO - PROCESS | 1936 | 12:42:01 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49459/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:02 INFO - PROCESS | 1936 | RSS: Main: 285978624 12:42:02 INFO - PROCESS | 1936 | 12:42:05 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49459/tests/kraken/driver.html?testName=audio-dft) 12:42:05 INFO - PROCESS | 1936 | RSS: Main: 300539904 12:42:05 INFO - PROCESS | 1936 | 12:42:09 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49459/tests/kraken/driver.html?testName=audio-fft) 12:42:10 INFO - PROCESS | 1936 | RSS: Main: 382607360 12:42:10 INFO - PROCESS | 1936 | 12:42:12 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49459/tests/kraken/driver.html?testName=audio-oscillator) 12:42:13 INFO - PROCESS | 1936 | RSS: Main: 287166464 12:42:13 INFO - PROCESS | 1936 | 12:42:15 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49459/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:15 INFO - PROCESS | 1936 | RSS: Main: 291139584 12:42:15 INFO - PROCESS | 1936 | 12:42:18 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49459/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:19 INFO - PROCESS | 1936 | RSS: Main: 328552448 12:42:19 INFO - PROCESS | 1936 | 12:42:22 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49459/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:22 INFO - PROCESS | 1936 | RSS: Main: 329117696 12:42:22 INFO - PROCESS | 1936 | 12:42:25 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49459/tests/kraken/driver.html?testName=json-parse-financial) 12:42:26 INFO - PROCESS | 1936 | RSS: Main: 327376896 12:42:26 INFO - PROCESS | 1936 | 12:42:28 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49459/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:28 INFO - PROCESS | 1936 | RSS: Main: 320004096 12:42:28 INFO - PROCESS | 1936 | 12:42:30 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:31 INFO - PROCESS | 1936 | RSS: Main: 327864320 12:42:31 INFO - PROCESS | 1936 | 12:42:33 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:34 INFO - PROCESS | 1936 | RSS: Main: 330137600 12:42:34 INFO - PROCESS | 1936 | 12:42:37 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:38 INFO - PROCESS | 1936 | RSS: Main: 331145216 12:42:38 INFO - PROCESS | 1936 | 12:42:41 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:41 INFO - PROCESS | 1936 | RSS: Main: 328683520 12:42:41 INFO - PROCESS | 1936 | 12:42:43 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49459/tests/kraken/driver.html?testName=ai-astar) 12:42:43 INFO - PROCESS | 1936 | RSS: Main: 392605696 12:42:43 INFO - PROCESS | 1936 | 12:42:43 INFO - PROCESS | 1936 | __start_tp_report 12:42:43 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 12:42:43 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 12:42:43 INFO - PROCESS | 1936 | |i|pagename|runs| 12:42:43 INFO - PROCESS | 1936 | |0;ai-astar;117;115;117;116;116;116;123;117;116;115 12:42:43 INFO - PROCESS | 1936 | |1;audio-beat-detection;166;150;174;210;150;152;151;126;189;123 12:42:43 INFO - PROCESS | 1936 | |2;audio-dft;217;215;214;213;226;286;215;210;214;217 12:42:43 INFO - PROCESS | 1936 | |3;audio-fft;83;79;81;155;57;56;62;65;138;57 12:42:43 INFO - PROCESS | 1936 | |4;audio-oscillator;97;96;142;80;82;84;132;81;80;130 12:42:43 INFO - PROCESS | 1936 | |5;imaging-gaussian-blur;90;87;91;87;90;87;92;88;90;87 12:42:43 INFO - PROCESS | 1936 | |6;imaging-darkroom;96;96;96;97;96;96;97;100;97;96 12:42:43 INFO - PROCESS | 1936 | |7;imaging-desaturate;104;105;105;103;105;105;105;105;104;99 12:42:43 INFO - PROCESS | 1936 | |8;json-parse-financial;60;61;61;60;60;60;62;82;58;58 12:42:43 INFO - PROCESS | 1936 | |9;json-stringify-tinderbox;66;67;67;77;83;72;73;71;72;71 12:42:43 INFO - PROCESS | 1936 | |10;stanford-crypto-aes;75;73;92;109;108;108;75;102;109;111 12:42:43 INFO - PROCESS | 1936 | |11;stanford-crypto-ccm;150;163;160;162;139;129;154;158;177;208 12:42:43 INFO - PROCESS | 1936 | |12;stanford-crypto-pbkdf2;178;178;182;185;178;178;231;188;212;222 12:42:43 INFO - PROCESS | 1936 | |13;stanford-crypto-sha256-iterative;74;72;72;73;77;79;100;102;76;71 12:42:43 INFO - PROCESS | 1936 | __end_tp_report 12:42:43 INFO - PROCESS | 1936 | __start_cc_report 12:42:43 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,2069 12:42:44 INFO - PROCESS | 1936 | __end_cc_report 12:42:44 INFO - PROCESS | 1936 | __startTimestamp1461094963991__endTimestamp 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | ------- Summary: start ------- 12:42:44 INFO - PROCESS | 1936 | Number of tests: 14 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#0] ai-astar Cycles:10 Average:116.80 Median:116.50 stddev:2.30 (2.0%) stddev-sans-first:2.44 12:42:44 INFO - PROCESS | 1936 | Values: 117.0 115.0 117.0 116.0 116.0 116.0 123.0 117.0 116.0 115.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#1] audio-beat-detection Cycles:10 Average:159.10 Median:159.00 stddev:26.74 (16.8%) stddev-sans-first:28.24 12:42:44 INFO - PROCESS | 1936 | Values: 166.0 150.0 174.0 210.0 150.0 152.0 151.0 126.0 189.0 123.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#2] audio-dft Cycles:10 Average:222.70 Median:216.00 stddev:22.63 (10.5%) stddev-sans-first:23.91 12:42:44 INFO - PROCESS | 1936 | Values: 217.0 215.0 214.0 213.0 226.0 286.0 215.0 210.0 214.0 217.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#3] audio-fft Cycles:10 Average:83.30 Median:63.50 stddev:35.08 (55.2%) stddev-sans-first:37.21 12:42:44 INFO - PROCESS | 1936 | Values: 83.0 79.0 81.0 155.0 57.0 56.0 62.0 65.0 138.0 57.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:81.50 stddev:24.61 (30.2%) stddev-sans-first:26.08 12:42:44 INFO - PROCESS | 1936 | Values: 97.0 96.0 142.0 80.0 82.0 84.0 132.0 81.0 80.0 130.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.00 stddev:1.91 (2.1%) stddev-sans-first:1.99 12:42:44 INFO - PROCESS | 1936 | Values: 90.0 87.0 91.0 87.0 90.0 87.0 92.0 88.0 90.0 87.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:1.25 (1.3%) stddev-sans-first:1.30 12:42:44 INFO - PROCESS | 1936 | Values: 96.0 96.0 96.0 97.0 96.0 96.0 97.0 100.0 97.0 96.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#7] imaging-desaturate Cycles:10 Average:104.00 Median:105.00 stddev:1.89 (1.8%) stddev-sans-first:2.00 12:42:44 INFO - PROCESS | 1936 | Values: 104.0 105.0 105.0 103.0 105.0 105.0 105.0 105.0 104.0 99.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:60.50 stddev:7.07 (11.7%) stddev-sans-first:7.45 12:42:44 INFO - PROCESS | 1936 | Values: 60.0 61.0 61.0 60.0 60.0 60.0 62.0 82.0 58.0 58.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:5.11 (7.1%) stddev-sans-first:4.95 12:42:44 INFO - PROCESS | 1936 | Values: 66.0 67.0 67.0 77.0 83.0 72.0 73.0 71.0 72.0 71.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:92.00 stddev:16.03 (17.4%) stddev-sans-first:15.06 12:42:44 INFO - PROCESS | 1936 | Values: 75.0 73.0 92.0 109.0 108.0 108.0 75.0 102.0 109.0 111.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:161.00 stddev:21.47 (13.3%) stddev-sans-first:22.46 12:42:44 INFO - PROCESS | 1936 | Values: 150.0 163.0 160.0 162.0 139.0 129.0 154.0 158.0 177.0 208.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.20 Median:186.50 stddev:20.43 (11.0%) stddev-sans-first:20.91 12:42:44 INFO - PROCESS | 1936 | Values: 178.0 178.0 182.0 185.0 178.0 178.0 231.0 188.0 212.0 222.0 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - PROCESS | 1936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.60 Median:73.50 stddev:11.56 (15.7%) stddev-sans-first:12.08 12:42:44 INFO - PROCESS | 1936 | Values: 74.0 72.0 72.0 73.0 77.0 79.0 100.0 102.0 76.0 71.0 12:42:44 INFO - PROCESS | 1936 | -------- Summary: end -------- 12:42:44 INFO - PROCESS | 1936 | 12:42:44 INFO - TEST-INFO | 1936: exit 0 12:42:44 INFO - TEST-OK | kraken | took 61305ms 12:42:44 INFO - SUITE-END | took 1174s 12:42:45 INFO - Completed test suite (00:19:34) 12:42:45 INFO - javascript benchmark 12:42:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2329.4292898239933, 2843.8720257588407, 2896.724811142942, 2899.0288253435097, 2900.695426980167, 4473.1501014807245, 4599.3560901473775, 4614.676972611915, 4632.00411911439, 4701.882452938674, 4525.298578720295, 4604.286335617976, 4636.976815115928, 4652.0, 4664.346991421204, 4243.9178009607585, 4288.742675439484, 4289.6997210195295, 4317.848875289365, 4362.738235705852, 3579.6241394653575, 3895.4351619015224, 4329.199098166833, 4353.891152721176, 4360.127974405116, 381.81820535089116, 4334.241507736134, 4339.2406328892475, 4340.8697739067875, 4413.86758397247, 4213.494380674308, 4241.597048280409, 4283.357496375537, 4317.15815415994, 4365.759883206422, 4254.7872606369865, 4286.121345124242, 4289.935650965238, 4322.351647252905, 4330.913381732347, 4352.368906508538, 4538.364628951949, 4554.111948170108, 4562.771861406916, 4568.132054909556, 5055.797768089272, 5119.744012799345, 5130.050940576006, 5167.819126330598, 5174.922376164361, 5123.2315152727015, 5129.333186685707, 5166.819161329336, 5174.922376164361, 5239.345081864767, 4721.315409265671, 4815.49437309081, 4867.537583929521, 4869.561739443467, 4872.293517439987, 5168.198929165986, 5280.445553216934, 5329.786808527616, 5347.572194224453, 5347.625666203328, 4133.607307305801, 5021.5229553192385, 5047.343845300087, 5057.140286151363, 5094.312267843794, 5023.572996295356, 5136.512031357015, 5147.897042059138, 5149.407818100892, 5160.587153027749, 4918.139325618003, 5114.130597798383, 5140.871478213, 5143.819966301162, 5164.457731938168, 5077.669951453144, 5099.949000509948, 5110.770015349244, 5111.488851114858, 5132.358455193101, 4074.144429669743, 4134.751914885056, 4180.435641188462, 4203.159368126387, 4229.471316085489, 3655.323765103464, 3718.3492888744363, 3738.9065273368383, 3750.9249815003545, 3811.923761524754, 4517.006258623098, 4537.546245375502, 4545.499995000548, 4552.453705555288, 4580.106879158532, 4995.825146119869, 5144.61415393847, 5163.070647283525, 5178.508041736029, 5209.583233341324, 4434.0245146068455, 4524.0, 4534.093181363741, 4558.156741002925, 4569.748663823521, 4528.366028755977, 4533.206688829508, 4547.090581883636, 4573.839915602938, 4575.725456472623, 5192.221166824931, 5332.573394128538, 5359.383670877821, 5397.055515284809, 5397.946020539823, 5107.591392688501, 5129.820456284012, 5134.5635620972635, 5141.948580514147, 5153.510416510426, 5292.603054770984, 5319.893602128013, 5338.225957236102, 5353.0, 5353.304070470814, 5118.206677964921, 5135.537801597874, 5171.120909445479, 5174.948250517597, 5194.3247377840635, 5180.7409629519125, 5192.610554208374, 5193.11717008102, 5197.298364720866, 5236.031334203185, 4848.32123502717, 5019.924701129559, 5031.84904452867, 5047.924281135859, 5048.848534543969, 4265.019045619587, 4953.18272485036, 4954.1825598775795, 4972.726500042532, 5057.797688092435, 4907.877303067452, 4934.18585933317, 4935.087008903364, 4941.629377796751, 4985.027919555652, 4868.48880867518, 5020.949790502048, 5022.4977502249485, 5044.697318160922, 5076.390833100052, 4794.7842347093765, 4822.903541929071, 4870.829520966749, 4930.260460930889, 4944.629152813625], "value": 4662.440732836129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.6402877697842, 459.48308153327486, 471.55202557570294, 501.2030870915244, 510.3136281701112, 29737.70262297379, 36119.0, 37342.62657373429, 37379.06552336193, 37442.0, 40731.0, 41882.7905860471, 41941.0, 42100.78949605255, 42179.0, 8087.433879628399, 9121.635134594624, 9366.531673416335, 9373.718788436357, 9407.52962351883, 8212.260896519312, 10799.730006749855, 11140.944295278512, 11142.721431964224, 11173.776524469546, 10842.783144337109, 10960.342379457219, 10967.561297548089, 10972.945135274313, 11001.889981100207, 8115.837683246361, 10755.946220268926, 10759.677209683721, 10894.891051089506, 11079.947404996512, 10806.891931080667, 10922.672319830339, 10943.945280273547, 10948.124150068055, 10973.725656858562, 36411.0, 37098.62901371006, 37105.0, 37107.62892371069, 37199.0, 3237.43344914639, 3626.7642603230706, 3646.08847788053, 3699.0567405311745, 3839.520059992501, 27462.0, 27591.172264832087, 27921.16236512908, 27960.161195163975, 28426.147215583565, 2587.8964841406323, 2646.8941242350284, 2658.548046832024, 2664.1341563991587, 2725.427660191362, 476087.0, 476227.0, 476828.0, 478686.0, 480473.0, 56491.43508564944, 56872.0, 57002.0, 57122.42877571254, 57123.0, 60077.399226008056, 61676.69161654163, 63803.36196638067, 63884.68057659775, 63921.72156556843, 15735.370585176694, 15810.0, 16019.359225630844, 16023.0, 16039.679206415807, 11348.829767553494, 11667.183297169116, 12231.816522752166, 12296.323702196287, 12720.745585088247, 17609.471715848544, 17648.470545883643, 17653.20560574777, 17653.646927061385, 17660.823391766175, 7291.573585917238, 7553.584552849646, 7599.820098945558, 7621.161672216052, 7645.273698998586, 801.5711594297023, 921.8386782313069, 926.7173512078881, 1014.7919676466307, 1073.7959787640323, 28043.859780700965, 28361.37918626233, 28472.430551389272, 28784.39623666886, 29036.564451533242, 996.2627655534852, 1022.5602990714065, 1087.8857719939292, 1097.3415950429837, 1101.895319944604, 14663.633409164857, 14681.559553213421, 14810.111393316436, 15004.174770387515, 15043.699126017418, 616648.0, 618810.0, 624188.0, 624935.0, 627530.0, 56176.43823561712, 56836.14745778817, 56919.0, 57036.71481642565, 57073.429265706814, 1249.743802520481, 1376.126159888478, 1416.5113036002563, 1421.175718083509, 1425.8075159853288, 27031.59452608173, 27148.32129196707, 27764.583531246644, 27900.860495698205, 27903.860480697465, 33451.49822752612, 42449.93875153146, 42975.9256018602, 43119.56880431156, 43181.0, 298106.0, 328981.0, 331869.0, 332053.0, 332578.0, 22039.0, 24203.75796242015, 24915.127970520945, 25124.24627261185, 25200.747992520573, 40033.79983100066, 40443.79778101091, 42236.36645450259, 42493.362599560416, 42792.14415711606, 33819.4927076099, 34645.653543465254, 34865.0, 35172.44114788914, 35814.820925895205, 34275.4858677125, 34471.82764086164, 35015.0, 35096.29807403889, 35151.12122196965, 34739.957801266006, 34982.30035399328, 34987.65012349946, 35809.64190358063, 36654.8167259162, 29270.0, 29846.25384365408, 29910.0, 30029.54955675623, 30063.947761828233, 20895.686564701846, 22618.0, 22745.658815117455, 23083.538329232986, 23141.958611862843], "value": 18842.035782975487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.16834449111536, 365.19838953497066, 369.6986955631162, 377.7110510459497, 379.11476701901057, 19277.325293614726, 19450.319238826643, 19811.20755169795, 19986.600267994632, 20008.799472031653, 18835.905820470914, 19110.0, 19983.803401285746, 20115.19539218433, 20186.889721065334, 4621.0, 4953.504649535044, 5135.0, 5164.147915593941, 5179.145441002249, 5852.268466441695, 5872.853178670524, 5894.764209431618, 5920.97039514804, 5922.022866227067, 5323.387810401795, 5753.942460575404, 5892.793752218673, 5895.086261629454, 5897.498712609433, 5209.036328279242, 5330.7334633268565, 5611.3266408031095, 5664.291963504562, 5691.971540142315, 5222.556082732935, 5453.890922181574, 5610.579206559483, 5618.0449323614675, 5625.38120806711, 12562.748745025048, 12687.238765674088, 12837.422315995802, 14016.0, 23393.649095263583, 1929.421173647914, 1946.2701486942397, 2015.748031496063, 2040.5204776877422, 2087.8538502304846, 8103.635336409876, 8904.0, 9513.714588562354, 9560.996095409952, 9774.51127443625, 1421.211227768588, 1593.7051645445513, 1633.5099470158905, 1648.826873178323, 1706.8719846011475, 110288.0, 117430.0, 118872.0, 119342.40328798388, 121520.0, 9539.856902146474, 10909.127269818555, 11089.0, 11117.610883619196, 11183.888161118284, 14609.415623374945, 14612.634684132981, 14647.0, 14662.633434164018, 14682.0, 6789.592624442451, 6832.555883867532, 8055.7583272501915, 8364.790880228044, 8370.874436883452, 8056.5971701415865, 8069.31410830086, 8080.636371363303, 8169.30560902318, 8179.182081791892, 4385.451818522684, 4410.713303635253, 4425.35832304314, 4430.224710675683, 4507.301368287921, 1501.6546194375137, 1655.2716804605934, 1875.8780679256079, 1879.332836842901, 1902.0, 772.6214155063979, 816.8202995341015, 840.7771940435789, 911.0343036381457, 933.8926023507248, 13155.473781048842, 19747.901260493607, 20228.79771202269, 20345.79654203439, 20353.389398317777, 862.7023676831483, 900.4912224593083, 924.6255266617031, 931.7996630724426, 973.9919183645035, 9279.443233406017, 9848.799140279294, 10545.630902918361, 10637.521311540999, 10686.89313106859, 229168.0, 231865.0, 232569.0, 233262.0, 233302.0, 78228.21771782324, 78641.0, 119280.0, 121621.0, 126172.34867309521, 796.4663675337538, 879.9516026618469, 905.7871400220943, 909.1681111782777, 910.9726708198765, 13133.474661013452, 16397.42609008679, 17756.91121544384, 18005.909970450062, 18074.915505069737, 38475.807620961714, 42120.0, 45393.638190854326, 47151.349702760235, 47746.28380574364, 68032.63934721179, 70412.0, 70968.29031709823, 72713.27286727064, 73791.63104184445, 6649.335066493312, 7187.2453392393045, 7305.086864141982, 7513.962430188033, 7867.763967080997, 10161.339512931636, 11775.470103845177, 11977.981871541017, 12449.128561000643, 12466.1273710843, 10341.534630941474, 10427.667237822257, 10505.579776808841, 10795.401115571025, 10933.726656833325, 10407.011333923265, 10527.157827373638, 10694.304870183412, 10985.340879547253, 11058.557657693602, 10830.079443247412, 10864.945675271572, 10895.673129806119, 10987.835182472429, 11246.66260012201, 7253.891191632024, 7264.23725508814, 7365.7790266292095, 7398.778036658909, 7547.849043019219, 8853.867191992254, 10834.404107774148, 10835.458227088457, 11089.611863584736, 11153.163512736739], "value": 8589.296390726706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.8670132986697, 1493.3503925792281, 1507.2463768115945, 1588.6822635472909, 1861.6369807887468, 6313.905291420632, 8318.085010648823, 8552.102029286929, 8570.0, 8605.053444121142, 7823.139454659983, 8219.835603287946, 8401.537915414665, 8404.66381344747, 8412.789680257996, 2264.6829443877828, 2303.6429353450244, 2306.5963456395148, 2309.3764683535437, 2311.0986715181093, 2271.5343354612264, 2300.3559003479054, 2306.2504685977124, 2311.0293676655847, 2323.7327707313707, 2282.6233671444193, 2294.369048511656, 2305.1470955746313, 2321.1875843454823, 2325.500017496238, 2265.9660105098437, 2279.566882292359, 2285.988570057147, 2289.8969546370454, 2291.9885400572975, 2206.227820262895, 2215.280033988944, 2215.656573231152, 2220.378294077661, 2220.5669894370603, 8178.0, 8408.957955210246, 8427.452215605967, 8489.915100848973, 8499.830003399897, 4905.8773530661665, 5058.4435712071645, 5066.923996140061, 5071.061853557067, 5085.618578606583, 3282.770206085575, 3297.868085276586, 3374.8481318340732, 3388.1868351595685, 3388.7797293176113, 978.3542861711308, 985.1724551376806, 1000.0699349604864, 1005.3415013166388, 1020.1991436722137, 10068.345557538736, 10111.977604479134, 10870.673879783619, 11153.721156970978, 11173.83239251412, 3430.0, 3606.6032736398975, 4047.9595204048173, 4201.747895126241, 4240.597143271384, 7974.122846486882, 8090.474119182234, 8100.554469504234, 8114.431989760778, 8114.918850811534, 4929.0881186979805, 4942.159832828428, 4942.4316203637045, 4960.627952903475, 4973.005398920231, 4233.767142807175, 4410.625096866802, 4570.017446249072, 4744.525547445228, 4747.0505898820375, 3537.7346698997676, 3568.839402226906, 3642.7996460194413, 3658.835352409148, 3671.2657468506404, 1119.5521791283552, 1142.1034487927104, 1142.5601143559788, 1156.5200441816553, 1160.9129315301386, 530.7160669042121, 586.0915580849751, 706.2901783707443, 785.7249962513189, 801.6873419366452, 5224.555912747459, 5417.51242388187, 5461.726913654302, 5490.69801160932, 5665.3201615806365, 511.7697036333665, 527.9994410591906, 534.5429657642737, 535.6411204492999, 654.3358491131505, 3064.923376915595, 3505.5968563615, 3735.701143908481, 3777.8866634001024, 3783.451399547105, 9895.80208395828, 9943.850842237372, 10277.17782577392, 10428.687139385831, 10437.425941573287, 10179.083882450466, 10181.592736290613, 10214.029667181605, 10344.5344959477, 10351.896481035245, 7511.361534269539, 7612.276833700789, 7775.650095745592, 7910.644021019068, 7941.64262608184, 5471.316085489314, 5612.102063669793, 5614.0, 5646.576506761927, 5750.6262092963825, 9368.34421590482, 9370.437773733598, 9505.239580833379, 9593.616255349987, 9796.608135674493, 9132.22376098039, 9301.395409298373, 9742.95128524353, 9765.974572670051, 10028.849567256351, 7076.540024898365, 7159.463040271896, 7252.746153884589, 7306.525075870051, 7315.122185337794, 7728.884066739116, 7867.173946735739, 7875.054993400829, 7877.015373078365, 7880.487768295043, 5772.544945026909, 6960.269171737097, 6996.545224560387, 7221.53060051095, 7266.927330726625, 5810.680412577349, 6947.7220911162985, 6960.47796415281, 7057.082579264663, 7138.714451421885, 6487.708053137526, 6614.272430032596, 6635.303293154342, 6660.833479162866, 6671.366220209073, 4534.886627834198, 4574.797641650791, 4651.116287905287, 4656.348111264493, 4700.130475861976, 4533.750643714628, 4600.240960241523, 4651.790669419816, 4670.5329467053025, 4835.129676658235], "value": 4220.453377842607, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.9272189230801, 288.6262290334017, 295.06464507511186, 299.08927316321797, 301.82192506421205, 4200.726952748069, 4289.678274129441, 4324.329728892023, 4338.240807858624, 4488.730676159425, 3810.6379893910034, 4128.504579450462, 4327.632151267146, 4423.778811059442, 4501.909961800762, 2786.4148528808973, 2787.6236708044457, 3098.9225269368276, 3300.257442075526, 3421.0250078727568, 2776.9166924992182, 3106.922326941822, 3164.5727826743323, 3201.967980320202, 3450.46517789743, 2935.5890175375357, 3386.3735208986295, 3392.542006829071, 3434.2959693262947, 3439.0, 3361.1933136047296, 3371.275175837207, 3381.8839782871837, 3391.9491207631904, 3402.336544373848, 3007.293286077792, 3013.050888969967, 3022.3804120155314, 3023.546468029791, 3026.9545956810666, 3921.29416704993, 3948.8617898373705, 4008.498937632796, 4010.0977280112033, 4053.169100334454, 3897.941530877039, 3944.3886197639413, 3951.288768021755, 3962.1085255817497, 3962.8811135665687, 4249.086446414005, 4257.0, 4589.954100458986, 4657.743824089674, 4678.766061696935, 3391.2708767614836, 3402.1494626343415, 3405.5062016007787, 3405.897823065312, 3412.5051867479324, 3761.6990640748672, 4858.562729354339, 4862.319275301461, 4872.756362181876, 4879.707217566922, 2750.642416485884, 3320.8007519548905, 3342.5487559179687, 3348.079278198515, 3356.7482438817187, 3371.713404360608, 3378.8986330410125, 3383.2725963917874, 3385.5768028996376, 3406.4500652203187, 3854.4989151410136, 3862.9034274143373, 3864.4976153099915, 3879.96120038796, 3883.7475564088245, 4536.115457485825, 4598.839040633562, 4666.113438446684, 4668.883277918011, 4677.789499472532, 954.6706386296717, 1044.7179261599326, 1118.8531754951077, 1142.5144313666658, 1146.587228597721, 758.3440324119707, 776.3556248313886, 776.4642396746227, 819.1521774963004, 827.6978902700471, 4047.372657238133, 4054.2094291613444, 4066.6136717012428, 4113.526944401432, 4113.650339721097, 4223.324268117087, 4357.716748411343, 4383.882110061913, 4738.976305118453, 4766.237402015661, 3862.2854771867296, 4090.795460226977, 4133.338665813455, 4201.810918508674, 4273.0, 2517.0, 2533.7331334332835, 2551.617257411366, 2558.85926274053, 2561.142017424184, 5126.615503837227, 5136.332276804066, 5147.408048074444, 5186.662866913714, 5204.7918083276245, 3641.8543258269374, 4596.8161273548685, 4857.465678775332, 4980.5019498049905, 5013.498650134958, 4193.002003567464, 4522.128448435267, 4762.6189904808225, 5049.267856160836, 5117.334746483008, 4810.0, 4816.447282940047, 4822.95177048225, 4834.6374021947795, 4858.562729354375, 4180.979095104505, 4568.269076947672, 4819.698681356084, 4821.5178482152905, 4833.758312084452, 5046.4701206374275, 5092.261622064854, 5094.541491265655, 5129.461406552258, 5138.588912887035, 3274.6561611030497, 3318.2533929865585, 3322.302316513559, 3323.26888084621, 3333.3999480405323, 4373.781310934377, 4783.402074740658, 4783.904321913612, 4803.159447096674, 4810.63920205993, 4605.148047611203, 4645.02454484562, 4674.088552732184, 4697.224957881911, 4712.693674911257, 4244.830206791818, 4362.345648152803, 4564.42944631921, 4668.486466488752, 4668.92996605058, 3809.1619843634357, 4190.224808410454, 4577.176108300538, 4593.586577208067, 4618.30725391194, 4378.2119218540965, 4382.868513944587, 4579.793909274024, 4628.259478483426, 4653.324338505306, 3969.900752481211, 4480.686351955332, 4507.008458139204, 4532.660050496292, 4596.954030459653], "value": 3451.277824691109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.2395829171827, 697.2434908124684, 706.1314583062832, 712.4941291682902, 725.6480606905651, 14156.07985480943, 14594.927025364885, 14605.488807891727, 14610.26948652567, 14630.195339256337, 12931.612051638442, 13223.471061157567, 13448.462061517552, 13449.932750336258, 13452.730945381087, 893.2853717026385, 914.1863741270241, 923.833709932212, 924.958376873039, 925.8611208318772, 904.2630256341093, 906.1391677905983, 909.9545022748869, 911.1389736698823, 912.2337236721153, 936.967206147785, 951.0869565217365, 962.9181519570844, 965.9903400966006, 966.8888077871029, 963.5471328475641, 968.2060710217625, 968.8885778135534, 969.2294625772528, 969.8690676758617, 725.1914115760941, 725.3580581185636, 725.804032911111, 727.5016613619688, 727.8071311102563, 12508.249505029728, 12779.808302875465, 12831.743365132645, 12834.422450989729, 12865.485380584765, 10849.728756781144, 10940.507677154546, 11032.704301935117, 11261.605843795509, 11541.365224912626, 8911.420757650732, 9189.954050229706, 9193.586288617029, 9237.983821779599, 9511.381760185566, 3266.3630592034565, 3323.401787678217, 3377.2738861144726, 3382.475716263983, 3406.67636574525, 76249.23750762477, 76507.61746191289, 77439.99999999943, 80377.19622803814, 80445.79331310035, 8035.839283214303, 8054.23484768946, 8126.024877014796, 8148.185181481805, 8391.748247552583, 18383.90808045951, 18385.26458941654, 18389.264429422674, 18442.0, 18451.16969736365, 7204.711811527585, 7288.890666640004, 7409.777706668809, 7447.180810110884, 7487.775366739006, 3169.7147256747003, 3747.606501317377, 3781.6029316921877, 3787.4886890270013, 3791.521306690393, 3288.473844184919, 3395.575553055868, 3411.573553305837, 3414.6926776590226, 3418.0, 592.762894842058, 594.191899017336, 601.290477236856, 603.3996173807091, 617.2438762515882, 530.4138926486178, 539.840746979642, 550.6751016900048, 555.3779766661365, 567.4495739133034, 7422.0, 7594.848103037908, 7642.312191902755, 7708.49894756839, 7872.488288261245, 459.2422502870291, 519.8064169205945, 520.2040877457496, 526.5261264861579, 534.9197620356978, 3542.574891013095, 3560.412531932254, 3565.3404120237838, 3567.143885467521, 3649.379605467077, 87779.0, 89257.55371223231, 89354.10645893587, 89822.75443113975, 90142.64786028214, 55603.72198139064, 65768.0, 74968.0, 75644.62177689077, 75822.0, 65777.85776058075, 67006.32993670001, 67643.66178169078, 68416.63166736637, 69500.6524967382, 7832.530048197126, 7968.0836703779805, 7978.680852766057, 7995.920040799518, 8117.1962047136585, 40959.795201024994, 41014.0, 41050.79474602608, 41122.794386029076, 41480.79259603683, 59464.70267648634, 60349.0, 60448.79102418015, 60516.78966420735, 60526.394736052076, 10997.725056873642, 11036.558537658404, 11054.281471704313, 11355.030293367465, 11363.204575679623, 11216.495257713586, 11823.763524729286, 11890.881091188978, 12058.276503409823, 12159.635210943687, 9683.564239609375, 10992.725181870517, 11689.649310520697, 11719.121065919919, 11796.410179491162, 11076.778464430827, 11077.712756396122, 11272.323660580392, 11465.828012579985, 12033.097517686034, 9263.583138758906, 10652.84020739705, 10781.9460902695, 10862.837057443987, 10876.465148826821, 8922.288778368149, 9052.094790520896, 9125.543722813965, 9196.54017299119, 9344.672936447192, 8902.955485222532, 9298.721038368589, 9300.255979521487, 9351.158395744416, 9416.670416535388], "value": 6517.362729149696, "unit": "score"}], "name": "dromaeo_css", "value": 6444.579319052637, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 117.0, 116.0, 116.0, 116.0, 123.0, 117.0, 116.0, 115.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 150.0, 174.0, 210.0, 150.0, 152.0, 151.0, 126.0, 189.0, 123.0], "value": 159.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 215.0, 214.0, 213.0, 226.0, 286.0, 215.0, 210.0, 214.0, 217.0], "value": 222.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 81.0, 155.0, 57.0, 56.0, 62.0, 65.0, 138.0, 57.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 142.0, 80.0, 82.0, 84.0, 132.0, 81.0, 80.0, 130.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 90.0, 87.0, 92.0, 88.0, 90.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 100.0, 97.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 105.0, 103.0, 105.0, 105.0, 105.0, 105.0, 104.0, 99.0], "value": 104.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 62.0, 82.0, 58.0, 58.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 77.0, 83.0, 72.0, 73.0, 71.0, 72.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 92.0, 109.0, 108.0, 108.0, 75.0, 102.0, 109.0, 111.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 163.0, 160.0, 162.0, 139.0, 129.0, 154.0, 158.0, 177.0, 208.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 178.0, 182.0, 185.0, 178.0, 178.0, 231.0, 188.0, 212.0, 222.0], "value": 193.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 72.0, 73.0, 77.0, 79.0, 100.0, 102.0, 76.0, 71.0], "value": 79.6, "unit": "score"}], "name": "kraken", "value": 1635.0, "alertThreshold": 2.0}]} 12:42:45 INFO - Return code: 0 12:42:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:42:45 ERROR - # TBPL SUCCESS # 12:42:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:45 INFO - 2016-04-19 12:42:45.337 screenresolution[1941:11846] starting screenresolution argv=screenresolution get 12:42:45 INFO - 2016-04-19 12:42:45.340 screenresolution[1941:11846] Display 0: 1600x1200x32@60 12:42:45 INFO - 2016-04-19 12:42:45.346 screenresolution[1942:11849] starting screenresolution argv=screenresolution list 12:42:45 INFO - Available Modes on Display 0 12:42:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:45 INFO - Intel Iris: 12:42:45 INFO - Chipset Model: Intel Iris 12:42:45 INFO - Type: GPU 12:42:45 INFO - Bus: Built-In 12:42:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:45 INFO - Vendor: Intel (0x8086) 12:42:45 INFO - Device ID: 0x0a2e 12:42:45 INFO - Revision ID: 0x0009 12:42:45 INFO - Displays: 12:42:45 INFO - Display: 12:42:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:45 INFO - Display Serial Number: 1600x1200 60 12:42:45 INFO - Main Display: Yes 12:42:45 INFO - Mirror: Off 12:42:45 INFO - Online: Yes 12:42:45 INFO - Rotation: Supported 12:42:45 INFO - Return code: 0 12:42:45 INFO - Running post-action listener: _resource_record_post_action 12:42:45 INFO - Running post-run listener: _resource_record_post_run 12:42:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1888256; Write bytes: 693168640; Read time: 24; Write time: 1954 12:42:46 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 383621632; Write bytes: 377889792; Read time: 29127; Write time: 658 12:42:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1052672; Write bytes: 293994496; Read time: 20; Write time: 1212 12:42:46 INFO - Running post-run listener: _upload_blobber_files 12:42:46 INFO - Blob upload gear active. 12:42:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:46 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 12:42:46 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'] 12:42:46 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 12:42:47 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:42:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:42:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:48 INFO - (blobuploader) - INFO - Done attempting. 12:42:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:42:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:42:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:49 INFO - (blobuploader) - INFO - Done attempting. 12:42:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:49 INFO - Return code: 0 12:42:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c27d977fb1c6fb17acd1e0b9844da20d0ee7fee6cd93b303888ea15c0af847dd476a55abff167f632100403a69378d9bddb78c5ccc0b7fc1b5e824fdc0495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a1dad0196fd1a627a7abe542b2b422363a230eeab03292eb4ee96584e5f11232666fd3f0dc01e00f0bb0194480e38a5ee6eb3d9c1df1dc8e4a3990dc0c6d142"} 12:42:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:49 INFO - Contents: 12:42:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c27d977fb1c6fb17acd1e0b9844da20d0ee7fee6cd93b303888ea15c0af847dd476a55abff167f632100403a69378d9bddb78c5ccc0b7fc1b5e824fdc0495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a1dad0196fd1a627a7abe542b2b422363a230eeab03292eb4ee96584e5f11232666fd3f0dc01e00f0bb0194480e38a5ee6eb3d9c1df1dc8e4a3990dc0c6d142"} 12:42:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:49 INFO - Copying logs to upload dir... 12:42:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.966501 ========= master_lag: 2.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-19 12:42:49.556994) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 12:42:49.564234) ========= 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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c27d977fb1c6fb17acd1e0b9844da20d0ee7fee6cd93b303888ea15c0af847dd476a55abff167f632100403a69378d9bddb78c5ccc0b7fc1b5e824fdc0495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a1dad0196fd1a627a7abe542b2b422363a230eeab03292eb4ee96584e5f11232666fd3f0dc01e00f0bb0194480e38a5ee6eb3d9c1df1dc8e4a3990dc0c6d142"} build_url:https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011899 build_url: 'https://queue.taskcluster.net/v1/task/Qwj7NaM1RZ-iRScUqOT1Gg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c27d977fb1c6fb17acd1e0b9844da20d0ee7fee6cd93b303888ea15c0af847dd476a55abff167f632100403a69378d9bddb78c5ccc0b7fc1b5e824fdc0495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a1dad0196fd1a627a7abe542b2b422363a230eeab03292eb4ee96584e5f11232666fd3f0dc01e00f0bb0194480e38a5ee6eb3d9c1df1dc8e4a3990dc0c6d142"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 12:42:49.598665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:42:49.599019) ========= 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.2CmbRBSXJl/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.8F8dT7eoQ0/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.004869 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 12:42:49.648317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 12:42:49.648652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 12:42:49.649087) ========= ========= Total master_lag: 4.69 =========