builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1461774981.41 results: success (0) buildid: 20160427075308 builduid: 21f1ad005f55401dac54bd2ee286edf1 revision: 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:21.413265) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:21.413650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:21.413934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:22.119167) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 09:36:22.119555) ========= 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.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-27 09:36:22-- 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% 207M=0s 2016-04-27 09:36:22 (207 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.471996 ========= master_lag: 1.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 09:36:23.704177) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:23.704444) ========= 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.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.028183 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:23.796176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-27 09:36:23.796479) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 --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 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-27 09:36:23,876 truncating revision to first 12 chars 2016-04-27 09:36:23,876 Setting DEBUG logging. 2016-04-27 09:36:23,876 attempt 1/10 2016-04-27 09:36:23,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/099e2bf3ce03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 09:36:24,982 unpacking tar archive at: fx-team-099e2bf3ce03/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338692 ========= master_lag: 5.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-27 09:36:30.292297) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:30.292575) ========= script_repo_revision: 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 09:36:30.292941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-27 09:36:30.293227) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-27 09:36:37.436572) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-27 09:36:37.436833) ========= /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.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:36:37 INFO - MultiFileLogger online at 20160427 09:36:37 in /builds/slave/test 09:36:37 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 09:36:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:37 INFO - {'append_to_log': False, 09:36:37 INFO - 'base_work_dir': '/builds/slave/test', 09:36:37 INFO - 'blob_upload_branch': 'Fx-Team', 09:36:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:37 INFO - 'branch': 'Fx-Team', 09:36:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:37 INFO - 'config_files': ('talos/mac_config.py',), 09:36:37 INFO - 'default_actions': ('clobber', 09:36:37 INFO - 'read-buildbot-config', 09:36:37 INFO - 'download-and-extract', 09:36:37 INFO - 'populate-webroot', 09:36:37 INFO - 'create-virtualenv', 09:36:37 INFO - 'install', 09:36:37 INFO - 'run-tests'), 09:36:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:37 INFO - 'download_minidump_stackwalk': True, 09:36:37 INFO - 'download_symbols': 'ondemand', 09:36:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:37 INFO - 'installer_path': 'installer.exe', 09:36:37 INFO - 'log_level': 'info', 09:36:37 INFO - 'log_name': 'talos', 09:36:37 INFO - 'log_to_console': True, 09:36:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:37 INFO - 'opt_config_files': (), 09:36:37 INFO - 'pip_index': False, 09:36:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:37 INFO - 'cmd': ('bash', 09:36:37 INFO - '-c', 09:36:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:37 INFO - 'enabled': True, 09:36:37 INFO - 'halt_on_failure': False, 09:36:37 INFO - 'name': 'check_screen_resolution'},), 09:36:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:37 INFO - 'cmd': ('bash', 09:36:37 INFO - '-c', 09:36:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:37 INFO - 'enabled': True, 09:36:37 INFO - 'halt_on_failure': False, 09:36:37 INFO - 'name': 'check_screen_resolution'},), 09:36:37 INFO - 'run_cmd_checks_enabled': True, 09:36:37 INFO - 'sps_profile': False, 09:36:37 INFO - 'sps_profile_interval': 0, 09:36:37 INFO - 'suite': 'dromaeojs', 09:36:37 INFO - 'system_bits': '32', 09:36:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:37 INFO - 'title': 't-yosemite-r7-0084', 09:36:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:37 INFO - 'use_talos_json': True, 09:36:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:37 INFO - 'work_dir': 'build'} 09:36:37 INFO - ##### 09:36:37 INFO - ##### Running clobber step. 09:36:37 INFO - ##### 09:36:37 INFO - Running pre-action listener: _resource_record_pre_action 09:36:37 INFO - Running main action method: clobber 09:36:37 INFO - rmtree: /builds/slave/test/build 09:36:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:39 INFO - Running post-action listener: _resource_record_post_action 09:36:39 INFO - ##### 09:36:39 INFO - ##### Running read-buildbot-config step. 09:36:39 INFO - ##### 09:36:39 INFO - Running pre-action listener: _resource_record_pre_action 09:36:39 INFO - Running main action method: read_buildbot_config 09:36:39 INFO - Using buildbot properties: 09:36:39 INFO - { 09:36:39 INFO - "project": "", 09:36:39 INFO - "product": "firefox", 09:36:39 INFO - "script_repo_revision": "production", 09:36:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:36:39 INFO - "repository": "", 09:36:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:36:39 INFO - "buildid": "20160427075308", 09:36:39 INFO - "pgo_build": "False", 09:36:39 INFO - "basedir": "/builds/slave/test", 09:36:39 INFO - "buildnumber": 784, 09:36:39 INFO - "slavename": "t-yosemite-r7-0084", 09:36:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:36:39 INFO - "platform": "yosemite_r7", 09:36:39 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:36:39 INFO - "branch": "fx-team", 09:36:39 INFO - "repo_path": "integration/fx-team", 09:36:39 INFO - "revision": "099e2bf3ce03d6eee73577a32b7f0cc84d1f6223", 09:36:39 INFO - "stage_platform": "macosx64", 09:36:39 INFO - "builduid": "21f1ad005f55401dac54bd2ee286edf1", 09:36:39 INFO - "slavebuilddir": "test" 09:36:39 INFO - } 09:36:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:36:39 INFO - Running post-action listener: _resource_record_post_action 09:36:39 INFO - ##### 09:36:39 INFO - ##### Running download-and-extract step. 09:36:39 INFO - ##### 09:36:39 INFO - Running pre-action listener: _resource_record_pre_action 09:36:39 INFO - Running main action method: download_and_extract 09:36:39 INFO - mkdir: /builds/slave/test/build/tests 09:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:39 INFO - https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:36:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:36:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:36:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:36:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:36:42 INFO - Downloaded 1199 bytes. 09:36:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:36:42 INFO - Using the following test package requirements: 09:36:42 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:36:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:36:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'jsshell-mac.zip'], 09:36:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:36:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:36:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:36:42 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:36:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:36:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:36:42 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36: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')]} 09:36:42 INFO - https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:43 INFO - Downloaded 18117628 bytes. 09:36:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:46 INFO - Return code: 0 09:36:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:46 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')]} 09:36:46 INFO - https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:36:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:49 INFO - Downloaded 18117628 bytes. 09:36:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:52 INFO - Return code: 0 09:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:52 INFO - https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:36:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:36:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:36:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:36:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:36:53 INFO - Downloaded 10840145 bytes. 09:36:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:36:54 INFO - Return code: 0 09:36: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')]} 09:36:54 INFO - https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:36:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:36:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:36:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:36:57 INFO - Downloaded 110187492 bytes. 09:36:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:36:57 INFO - mkdir: /builds/slave/test/properties 09:36:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:57 INFO - Contents: 09:36:57 INFO - build_url:https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:36:58 INFO - Running post-action listener: _resource_record_post_action 09:36:58 INFO - Running post-action listener: set_extra_try_arguments 09:36:58 INFO - ##### 09:36:58 INFO - ##### Running populate-webroot step. 09:36:58 INFO - ##### 09:36:58 INFO - Running pre-action listener: _resource_record_pre_action 09:36:58 INFO - Running main action method: populate_webroot 09:36:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:58 INFO - '--tppagecycles', 09:36:58 INFO - '7'], 09:36:58 INFO - 'tests': ['tsvgm']}}, 09:36:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['damp', 'tps']}, 09:36:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'tests': ['damp', 'tps']}, 09:36:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['dromaeo_dom']}, 09:36:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['a11yr', 09:36:58 INFO - 'ts_paint', 09:36:58 INFO - 'tpaint', 09:36:58 INFO - 'sessionrestore', 09:36:58 INFO - 'sessionrestore_no_auto_restore', 09:36:58 INFO - 'tabpaint']}, 09:36:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['tsvgx', 09:36:58 INFO - 'tsvgr_opacity', 09:36:58 INFO - 'tart', 09:36:58 INFO - 'tscrollx', 09:36:58 INFO - 'cart']}, 09:36:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:58 INFO - 'tsvgr_opacity', 09:36:58 INFO - 'tart', 09:36:58 INFO - 'tscrollx', 09:36:58 INFO - 'cart']}, 09:36:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'talos_options': ['--disable-e10s'], 09:36:58 INFO - 'tests': ['tp5o']}, 09:36:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'tests': ['tp5o']}, 09:36:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'talos_options': ['--disable-e10s', 09:36:58 INFO - '--xperf_path', 09:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:58 INFO - 'tests': ['tp5n']}, 09:36:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:58 INFO - 'talos_options': ['--xperf_path', 09:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:58 INFO - 'tests': ['tp5n']}}, 09:36:58 INFO - 'talos.zip': {'path': '', 09:36:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:58 INFO - Running post-action listener: _resource_record_post_action 09:36:58 INFO - ##### 09:36:58 INFO - ##### Running create-virtualenv step. 09:36:58 INFO - ##### 09:36:58 INFO - Running pre-action listener: _resource_record_pre_action 09:36:58 INFO - Running main action method: create_virtualenv 09:36:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36: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 09:36:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:58 INFO - Using real prefix '/tools/python27' 09:36:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:02 INFO - Installing pip.................done. 09:37:02 INFO - Return code: 0 09:37:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37: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 09:37: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 09:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:02 INFO - 'HOME': '/Users/cltbld', 09:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:02 INFO - 'LOGNAME': 'cltbld', 09:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:02 INFO - 'MOZ_NO_REMOTE': '1', 09:37:02 INFO - 'NO_EM_RESTART': '1', 09:37:02 INFO - 'PAGER': '/bin/cat', 09:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:02 INFO - 'PWD': '/builds/slave/test', 09:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:02 INFO - 'SHELL': '/bin/bash', 09:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:02 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:02 INFO - 'USER': 'cltbld', 09:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:02 INFO - 'XPC_FLAGS': '0x0', 09:37:02 INFO - 'XPC_SERVICE_NAME': '0', 09:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37: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 09:37:02 INFO - Cleaning up... 09:37:02 INFO - Return code: 0 09:37:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37: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 09:37: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 09:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:02 INFO - 'HOME': '/Users/cltbld', 09:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:02 INFO - 'LOGNAME': 'cltbld', 09:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:02 INFO - 'MOZ_NO_REMOTE': '1', 09:37:02 INFO - 'NO_EM_RESTART': '1', 09:37:02 INFO - 'PAGER': '/bin/cat', 09:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:02 INFO - 'PWD': '/builds/slave/test', 09:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:02 INFO - 'SHELL': '/bin/bash', 09:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:02 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:02 INFO - 'USER': 'cltbld', 09:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:02 INFO - 'XPC_FLAGS': '0x0', 09:37:02 INFO - 'XPC_SERVICE_NAME': '0', 09:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:02 INFO - Downloading/unpacking psutil>=0.7.1 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:06 INFO - Installing collected packages: psutil 09:37:06 INFO - Running setup.py install for psutil 09:37:06 INFO - building 'psutil._psutil_osx' extension 09:37: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:37: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 09:37: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 09:37: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 09:37:07 INFO - building 'psutil._psutil_posix' extension 09:37:07 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 09:37:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:07 INFO - ^ 09:37:07 INFO - 1 warning generated. 09:37:07 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 09:37:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:07 INFO - Successfully installed psutil 09:37:07 INFO - Cleaning up... 09:37:07 INFO - Return code: 0 09:37:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:07 INFO - 'HOME': '/Users/cltbld', 09:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:07 INFO - 'LOGNAME': 'cltbld', 09:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:07 INFO - 'MOZ_NO_REMOTE': '1', 09:37:07 INFO - 'NO_EM_RESTART': '1', 09:37:07 INFO - 'PAGER': '/bin/cat', 09:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:07 INFO - 'PWD': '/builds/slave/test', 09:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:07 INFO - 'SHELL': '/bin/bash', 09:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:07 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:07 INFO - 'USER': 'cltbld', 09:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:07 INFO - 'XPC_FLAGS': '0x0', 09:37:07 INFO - 'XPC_SERVICE_NAME': '0', 09:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:37:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:37:10 INFO - Installing collected packages: mozsystemmonitor 09:37:10 INFO - Running setup.py install for mozsystemmonitor 09:37:10 INFO - Successfully installed mozsystemmonitor 09:37:10 INFO - Cleaning up... 09:37:10 INFO - Return code: 0 09:37:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:10 INFO - 'HOME': '/Users/cltbld', 09:37:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:10 INFO - 'LOGNAME': 'cltbld', 09:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:10 INFO - 'MOZ_NO_REMOTE': '1', 09:37:10 INFO - 'NO_EM_RESTART': '1', 09:37:10 INFO - 'PAGER': '/bin/cat', 09:37:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:10 INFO - 'PWD': '/builds/slave/test', 09:37:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:10 INFO - 'SHELL': '/bin/bash', 09:37:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:10 INFO - 'USER': 'cltbld', 09:37:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:10 INFO - 'XPC_FLAGS': '0x0', 09:37:10 INFO - 'XPC_SERVICE_NAME': '0', 09:37:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:14 INFO - Downloading docopt-0.6.1.tar.gz 09:37:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:14 INFO - Running setup.py install for blobuploader 09:37:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:14 INFO - Running setup.py install for requests 09:37:14 INFO - Running setup.py install for docopt 09:37:14 INFO - Successfully installed blobuploader requests docopt 09:37:14 INFO - Cleaning up... 09:37:15 INFO - Return code: 0 09:37:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:15 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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37:15 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 09:37:15 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 09:37:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:15 INFO - 'HOME': '/Users/cltbld', 09:37:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:15 INFO - 'LOGNAME': 'cltbld', 09:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:15 INFO - 'MOZ_NO_REMOTE': '1', 09:37:15 INFO - 'NO_EM_RESTART': '1', 09:37:15 INFO - 'PAGER': '/bin/cat', 09:37:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:15 INFO - 'PWD': '/builds/slave/test', 09:37:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:15 INFO - 'SHELL': '/bin/bash', 09:37:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:15 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:15 INFO - 'USER': 'cltbld', 09:37:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:15 INFO - 'XPC_FLAGS': '0x0', 09:37:15 INFO - 'XPC_SERVICE_NAME': '0', 09:37:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-INQetu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-r43d_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MyPBKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-mRQuUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MYYVXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-yw00Ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:15 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-_RJn8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Mnk9cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-G1yniv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9qfi18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-FvrO2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-pj6_MP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-DBH6V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-iutwaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-33XJ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-pIJgyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-luULlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:17 INFO - Running setup.py install for manifestparser 09:37:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozcrash 09:37:17 INFO - Running setup.py install for mozdebug 09:37:17 INFO - Running setup.py install for mozdevice 09:37:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozfile 09:37:17 INFO - Running setup.py install for mozhttpd 09:37:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozinfo 09:37:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Running setup.py install for mozInstall 09:37:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Running setup.py install for mozleak 09:37:18 INFO - Running setup.py install for mozlog 09:37:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Running setup.py install for moznetwork 09:37:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Running setup.py install for mozprocess 09:37:18 INFO - Running setup.py install for mozprofile 09:37:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Running setup.py install for mozrunner 09:37:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:19 INFO - Running setup.py install for mozscreenshot 09:37:19 INFO - Running setup.py install for moztest 09:37:19 INFO - Running setup.py install for mozversion 09:37:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:19 INFO - Cleaning up... 09:37:19 INFO - Return code: 0 09:37:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37: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 09:37: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 09:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:19 INFO - 'HOME': '/Users/cltbld', 09:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:19 INFO - 'LOGNAME': 'cltbld', 09:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:19 INFO - 'MOZ_NO_REMOTE': '1', 09:37:19 INFO - 'NO_EM_RESTART': '1', 09:37:19 INFO - 'PAGER': '/bin/cat', 09:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:19 INFO - 'PWD': '/builds/slave/test', 09:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:19 INFO - 'SHELL': '/bin/bash', 09:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:19 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:19 INFO - 'USER': 'cltbld', 09:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:19 INFO - 'XPC_FLAGS': '0x0', 09:37:19 INFO - 'XPC_SERVICE_NAME': '0', 09:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-pc9hqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:19 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)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-R3wCV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-M6ZaC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gwrDjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-eBautV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-LLsa2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ESd0Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-YV_Xkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EeSHHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37: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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Mv8FyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:20 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)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Feh4Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-C6uIMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7EQiM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-x_x5ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-XscjVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-kbK0mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37: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)) 09:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-O_ujGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37: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)) 09:37: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)) 09:37: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)) 09:37: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)) 09:37: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)) 09:37: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)) 09:37:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:24 INFO - Downloading blessings-1.6.tar.gz 09:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:24 INFO - Installing collected packages: blessings 09:37:24 INFO - Running setup.py install for blessings 09:37:24 INFO - Successfully installed blessings 09:37:24 INFO - Cleaning up... 09:37:24 INFO - Return code: 0 09:37:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:24 INFO - Reading from file tmpfile_stdout 09:37:24 INFO - Current package versions: 09:37:24 INFO - blessings == 1.6 09:37:24 INFO - blobuploader == 1.2.4 09:37:24 INFO - docopt == 0.6.1 09:37:24 INFO - manifestparser == 1.1 09:37:24 INFO - mozInstall == 1.12 09:37:24 INFO - mozcrash == 0.17 09:37:24 INFO - mozdebug == 0.1 09:37:24 INFO - mozdevice == 0.48 09:37:24 INFO - mozfile == 1.2 09:37:24 INFO - mozhttpd == 0.7 09:37:24 INFO - mozinfo == 0.9 09:37:24 INFO - mozleak == 0.1 09:37:24 INFO - mozlog == 3.1 09:37:24 INFO - moznetwork == 0.27 09:37:24 INFO - mozprocess == 0.22 09:37:24 INFO - mozprofile == 0.28 09:37:24 INFO - mozrunner == 6.11 09:37:24 INFO - mozscreenshot == 0.1 09:37:24 INFO - mozsystemmonitor == 0.0 09:37:24 INFO - moztest == 0.7 09:37:24 INFO - mozversion == 1.4 09:37:24 INFO - psutil == 3.1.1 09:37:24 INFO - requests == 1.2.3 09:37:24 INFO - wsgiref == 0.1.2 09:37:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37: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 09:37: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 09:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:24 INFO - 'HOME': '/Users/cltbld', 09:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:24 INFO - 'LOGNAME': 'cltbld', 09:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:24 INFO - 'MOZ_NO_REMOTE': '1', 09:37:24 INFO - 'NO_EM_RESTART': '1', 09:37:24 INFO - 'PAGER': '/bin/cat', 09:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:24 INFO - 'PWD': '/builds/slave/test', 09:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:24 INFO - 'SHELL': '/bin/bash', 09:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:24 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:24 INFO - 'USER': 'cltbld', 09:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:24 INFO - 'XPC_FLAGS': '0x0', 09:37:24 INFO - 'XPC_SERVICE_NAME': '0', 09:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 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)) 09:37:24 INFO - Cleaning up... 09:37:24 INFO - Return code: 0 09:37:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:24 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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37:24 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 09:37:24 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 09:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:24 INFO - 'HOME': '/Users/cltbld', 09:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:24 INFO - 'LOGNAME': 'cltbld', 09:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:24 INFO - 'MOZ_NO_REMOTE': '1', 09:37:24 INFO - 'NO_EM_RESTART': '1', 09:37:24 INFO - 'PAGER': '/bin/cat', 09:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:24 INFO - 'PWD': '/builds/slave/test', 09:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:24 INFO - 'SHELL': '/bin/bash', 09:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:24 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:24 INFO - 'USER': 'cltbld', 09:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:24 INFO - 'XPC_FLAGS': '0x0', 09:37:24 INFO - 'XPC_SERVICE_NAME': '0', 09:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:25 INFO - Downloading/unpacking jsonschema 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:27 INFO - Downloading/unpacking functools32 (from jsonschema) 09:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:28 INFO - warning: no files found matching '*.txt' 09:37:28 INFO - no previously-included directories found matching 'build' 09:37:28 INFO - no previously-included directories found matching 'dist' 09:37:28 INFO - no previously-included directories found matching '.git*' 09:37:28 INFO - Installing collected packages: jsonschema, functools32 09:37:28 INFO - Running setup.py install for functools32 09:37:28 INFO - warning: no files found matching '*.txt' 09:37:28 INFO - no previously-included directories found matching 'build' 09:37:28 INFO - no previously-included directories found matching 'dist' 09:37:28 INFO - no previously-included directories found matching '.git*' 09:37:28 INFO - Successfully installed jsonschema functools32 09:37:28 INFO - Cleaning up... 09:37:28 INFO - Return code: 0 09:37:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 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 0x10151ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10152b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7c9d63d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, '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.TjOju89l7d/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.8vQP6pqQib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 09:37:28 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 09:37:28 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 09:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:28 INFO - 'HOME': '/Users/cltbld', 09:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:28 INFO - 'LOGNAME': 'cltbld', 09:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:28 INFO - 'MOZ_NO_REMOTE': '1', 09:37:28 INFO - 'NO_EM_RESTART': '1', 09:37:28 INFO - 'PAGER': '/bin/cat', 09:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:28 INFO - 'PWD': '/builds/slave/test', 09:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:28 INFO - 'SHELL': '/bin/bash', 09:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:37:28 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:28 INFO - 'USER': 'cltbld', 09:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:28 INFO - 'XPC_FLAGS': '0x0', 09:37:28 INFO - 'XPC_SERVICE_NAME': '0', 09:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:28 INFO - Downloading/unpacking flake8 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:37:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:37:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:32 INFO - no previously-included directories found matching 'docs/_build' 09:37:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:37:32 INFO - Running setup.py install for pep8 09:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:32 INFO - no previously-included directories found matching 'docs/_build' 09:37:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:37:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:37:32 INFO - Cleaning up... 09:37:32 INFO - Return code: 0 09:37:32 INFO - Running post-action listener: _resource_record_post_action 09:37:32 INFO - Running post-action listener: _start_resource_monitoring 09:37:33 INFO - Starting resource monitoring. 09:37:33 INFO - ##### 09:37:33 INFO - ##### Running install step. 09:37:33 INFO - ##### 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: install 09:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:33 INFO - Reading from file tmpfile_stdout 09:37:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:33 INFO - Reading from file tmpfile_stdout 09:37:33 INFO - Output received: 09:37:33 INFO - Usage: mozinstall [options] installer 09:37:33 INFO - Options: 09:37:33 INFO - -h, --help show this help message and exit 09:37:33 INFO - -d DEST, --destination=DEST 09:37:33 INFO - Directory to install application into. [default: 09:37:33 INFO - "/builds/slave/test"] 09:37:33 INFO - --app=APP Application being installed. [default: firefox] 09:37:33 INFO - mkdir: /builds/slave/test/build/application 09:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:38:07 INFO - Reading from file tmpfile_stdout 09:38:07 INFO - Output received: 09:38:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:07 INFO - Running post-action listener: _resource_record_post_action 09:38:07 INFO - ##### 09:38:07 INFO - ##### Running run-tests step. 09:38:07 INFO - ##### 09:38:07 INFO - Running pre-action listener: _resource_record_pre_action 09:38:07 INFO - Running main action method: run_tests 09:38:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:07 INFO - 2016-04-27 09:38:07.114 screenresolution[1649:7975] starting screenresolution argv=screenresolution get 09:38:07 INFO - 2016-04-27 09:38:07.116 screenresolution[1649:7975] Display 0: 1600x1200x32@60 09:38:07 INFO - 2016-04-27 09:38:07.122 screenresolution[1650:7977] starting screenresolution argv=screenresolution list 09:38:07 INFO - Available Modes on Display 0 09:38:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:07 INFO - Intel Iris: 09:38:07 INFO - Chipset Model: Intel Iris 09:38:07 INFO - Type: GPU 09:38:07 INFO - Bus: Built-In 09:38:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:07 INFO - Vendor: Intel (0x8086) 09:38:07 INFO - Device ID: 0x0a2e 09:38:07 INFO - Revision ID: 0x0009 09:38:07 INFO - Displays: 09:38:07 INFO - Display: 09:38:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:07 INFO - Display Serial Number: 1600x1200 60 09:38:07 INFO - Main Display: Yes 09:38:07 INFO - Mirror: Off 09:38:07 INFO - Online: Yes 09:38:07 INFO - Rotation: Supported 09:38:07 INFO - Return code: 0 09:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:07 INFO - Python 2.7.3 09:38:07 INFO - Return code: 0 09:38:07 INFO - grabbing minidump binary from tooltool 09:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:07 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 0x1013bae00>, '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 0x1011e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013b5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:07 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 09:38:07 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 09:38:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:07 INFO - Return code: 0 09:38:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:38:08 INFO - Return code: 0 09:38:08 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/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 09:38:08 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/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 09:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjOju89l7d/Render', 09:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:08 INFO - 'HOME': '/Users/cltbld', 09:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:08 INFO - 'LOGNAME': 'cltbld', 09:38:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:08 INFO - 'MOZ_NO_REMOTE': '1', 09:38:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:08 INFO - 'NO_EM_RESTART': '1', 09:38:08 INFO - 'PAGER': '/bin/cat', 09:38:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:08 INFO - 'PWD': '/builds/slave/test', 09:38:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:08 INFO - 'SHELL': '/bin/bash', 09:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vQP6pqQib/Listeners', 09:38:08 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 09:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:08 INFO - 'USER': 'cltbld', 09:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:08 INFO - 'XPC_FLAGS': '0x0', 09:38:08 INFO - 'XPC_SERVICE_NAME': '0', 09:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:08 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/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 09:38:08 INFO - mozversion application_buildid: 20160427075308 09:38:08 INFO - mozversion application_changeset: 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 09:38:08 INFO - mozversion application_display_name: Nightly 09:38:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:08 INFO - mozversion application_name: Firefox 09:38:08 INFO - mozversion application_remotingname: firefox 09:38:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:38:08 INFO - mozversion application_vendor: Mozilla 09:38:08 INFO - mozversion application_version: 49.0a1 09:38:08 INFO - mozversion platform_buildid: 20160427075308 09:38:08 INFO - mozversion platform_changeset: 099e2bf3ce03d6eee73577a32b7f0cc84d1f6223 09:38:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:38:08 INFO - mozversion platform_version: 49.0a1 09:38:08 INFO - using testdate: 1461775088 09:38:08 INFO - actual date: 1461775088 09:38:08 INFO - starting webserver on 'localhost:49275' 09:38:08 INFO - SUITE-START | Running 2 tests 09:38:08 INFO - TEST-START | dromaeo_css 09:38:08 INFO - operating with platform_type : mac_ 09:38:08 INFO - Initialising browser for dromaeo_css test... 09:38:08 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpwFnM8L/profile http://localhost:49275/getInfo.html) 09:38:11 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 09:38:11 INFO - PROCESS | 1668 | colorDepth:24 09:38:11 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 09:38:11 INFO - PROCESS | 1668 | __metrics 09:38:11 INFO - PROCESS | 1668 | 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" 09:38:11 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:38:11 INFO - TEST-INFO | 1668: exit 0 09:38:11 INFO - Browser initialized. 09:38:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:11 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpwFnM8L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:23 INFO - PROCESS | 1670 | RSS: Main: 239214592 09:38:23 INFO - PROCESS | 1670 | 09:39:15 INFO - PROCESS | 1670 | 1461775155671 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] 09:41:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 09:41:26 INFO - PROCESS | 1670 | RSS: Main: 367026176 09:41:26 INFO - PROCESS | 1670 | 09:44:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:44:29 INFO - PROCESS | 1670 | RSS: Main: 374120448 09:44:29 INFO - PROCESS | 1670 | 09:47:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:47:32 INFO - PROCESS | 1670 | RSS: Main: 376074240 09:47:32 INFO - PROCESS | 1670 | 09:50:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:50:35 INFO - PROCESS | 1670 | RSS: Main: 376815616 09:50:35 INFO - PROCESS | 1670 | 09:53:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:53:39 INFO - PROCESS | 1670 | RSS: Main: 345759744 09:53:39 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:56:42 INFO - PROCESS | 1670 | RSS: Main: 385056768 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | __start_tp_report 09:56:42 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 09:56:42 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 09:56:42 INFO - PROCESS | 1670 | |i|pagename|runs| 09:56:42 INFO - PROCESS | 1670 | |0;dojo.html;3349.5980482342115;3404.2340473393474;3422.520847081408;3439.484077388393;3478.930421391572;5508.338999320087;5588.30146231721;5594.049011668016;5596.27248457701;5636.239107720457;5594;5726.1697053927155;5726.226959360485;5741.224934633823;5746.885062298751;4326.783660816946;5101.897962040757;5139.743012849362;5165.870853228662;5199.818006369778;331.3179308023432;5220.92168617471;5231.686098834082;5250.159974404096;5293.3647962244395;5053.267276244942;5134.614903882224;5158.200478925773;5205.817796377109;5208.531232189102;4742.715437073752;5080.085584594772;5150.27896094547;5163.0190263849745;5182.222666600002;5134.204198349262;5144.485551444825;5157.767900444489;5170.25031370453;5191.844244672666;5410.188471729232;5523.613347065707;5533.750981205855;5590.86022849428;5639.125935480007;5140.640155189139;5998.730057147395;6071.355617224624;6170.691465426711;6201.596896201778;6145.201123853871;6216.937830621681;6219.880421524123;6223.68881555925;6233.563650544464;5588.413216612278;5673.063944449161;5677.290338707661;5680.233168522238;5731.971340143315;6203.534734894811;6255.530835187288;6276.152719382916;6313.873722525524;6316.842078948063;5835.9708201458725;5843.5325173985975;5847.386024467455;5857.062869940789;5897.823065308047;5358.356997160287;5902.439268269508;6081.391860813972;6084.361142080106;6103.755849766048;5953.404659534012;5994;6096.817095487142;6101.298350689727;6181.783637572664;6128.80869161996;6149.169862068564;6172.135900973867;6178.567500274938;6181.629102253879;4821.132196204647;4825.638077144228;4840.806367745321;4879.682820616648;4883.949950760603;4287.099709061132;4368.584984426538;4380.3210502372185;4402.581754733295;4438.400815889813;5598.300212473441;5599.860003499945;5611.242482264924;5612.522935550524;5612.663240205601;6101.792513241593;6145.508359331243;6152.600080994721;6164.753409863645;6166.845828854225;5589.077802162597;5654.575906806924;5657.179708942181;5705.657660540381;5706.572007099401;5320.467953204726;5325.121354976463;5334.813281535127;5336.759845806948;5342.492463215988;6214.751409943642;6242.500599952084;6283.120363149163;6292.433680968735;6313.715882785285;5266.999270138662;6223.3465486125115;6257.906131408124;6272.905906411499;6279.843003924939;6272.529560283088;6297.118403423433;6305.936940630535;6306.621602703853;6308.053791931247;5978.252718410199;5993.940060599338;6003.219581454383;6087.908681369871;6123.020316749299;4994.500549944976;6128.142060111499;6208.255009398901;6232.345603711545;6244.125822384779;4974.253861920741;6043.8186854394435;6049.3950604939155;6049.848753781014;6054.606450580698;5833.591648584558;5969.552283578662;6029.638221706711;6044.24446944132;6084.39156084388;5845.240118784531;5848.005839007294;5852.502537284208;5854.443827836349;5855.063189889597;4860.635452341159;5975.223220981245;6049.969750151221;6051.152838602511;6104.633721976696;5665.915011274752;5757.395473475225;5789.9710501447225;5792.594518383673;5802.883942321215 09:56:42 INFO - PROCESS | 1670 | |1;ext.html;499.45309885675186;525.2200482283805;539.107776629678;564.5004171308391;588.6850534963795;28953.565696514543;29524.557131643014;36974.630253697454;37929;38015.619843801556;37273.81363093187;40055.79972100136;40160.39759403611;40216.994575135635;40281.19437611246;8349.123342049088;9275.907240927589;9485.715428537154;9486.478243696594;9493.572789224467;8402.201790829862;11082.6121085762;11283.717907052307;11325.660230193067;11335.886641133608;11219.214654974156;11352.262102963323;11367.317960922372;11380.601678941239;11386.430678466086;8279.62741676626;8500.957495212484;8511.404201705822;11256.437178141101;11343.205975581672;8439.789005274917;10996.505157267862;11264.774704505946;11277.718057048558;11278.984891359734;36038.099047523756;36566.08584785375;36647;36655.450168247495;36731.4490282646;3163.43058249515;3189.599563340441;3235.822029788361;3242.01118658809;3383.9830800846084;26216.344591385176;26714.19857404281;26948.73051269482;27253.77358018894;27859.86070069657;2536.1377131775284;2567.768900798937;2574.45936353366;2587.262630150417;2651.814372993891;285189;285665;286272;286277;345541;56459;56660.43339566552;56684;56806;56820.86358272894;59561.70219148963;60341.094883576785;60611;60667.69666151729;60743;16053.43812966564;16269.779766517322;16270.51188464348;16466.341346346013;16642.66714665724;11337.319760814378;11429.59996400122;12427.37863106841;12541.498340066477;12558.183718058297;18410.35563755262;18421;18473.44579662612;18477.228415737747;18514.074296285133;6669.333066693292;7637.350825179811;7714.961425192951;7788.870613760973;7824.530528168328;973.756560859785;974.0648976982035;975.9024097590326;1078.7840789781337;1136.431784107946;27736.02923897654;27929.44141117166;27980.86009569939;28894.855525722236;28940.565891511644;1051.3008849115254;1070.6145787516489;1077.1329080090602;1090.2640717515644;1111.8943700348455;13942.651433714036;14099.224542650252;14419.711605768036;14445.349959251858;14614.853851461561;481625;482968;483269;487229;487961;54216.45783542193;54574.18138727922;54824;55247;55247.447525525036;1348.211296391622;1451.9419223230952;1493.4698182145182;1496.9027013244104;1512.3950419832156;26812.224454907857;27135.72864271411;27738.44523109567;27742.306442339104;27780.16659500218;47504.524954751396;48015.75992120017;48775.268370973754;48805.26792098051;48840.755796220794;388771;390617.04691476363;392182;392318;393336;21453.785462145177;24054.518909621358;24557.87721061383;24808.379790505383;25250.621240681037;38771;39028.6097139025;39938.60061399349;40024;40674.593254067084;32752.50871236981;33335.16662083467;33435.66564334326;33952.83023584965;34176;32665;33127.66872331342;33181.336373271915;33404.164895877795;33713.66286337203;34286.48570271398;34521.82739086288;34734.826325868205;35114.648853511135;35116.473252900716;29145.979890703722;29664.55503167411;30753.077407677807;30781.69218307788;30910.918117865767;17921.19354629071;23050.654240186745;23321.300360989197;23500.647490288;24452.877735611208 09:56:42 INFO - PROCESS | 1670 | |2;jquery.html;388.5861557441325;391.77179542135394;392.1764294980541;392.2958289869684;399.82407740594135;13644.146183063387;19796.604067918634;19814.70277945832;20603;20649.761014339132;13865.237411942338;18283.360082397117;19054.714179287323;19747.802521974776;20555;5696.401877802833;5862.032764593837;5895.056790913454;5901.144334071557;5906.497947674453;5830.533557315404;5883.970580147094;5938.376470470603;6027.668478233695;6031.517478601701;5590.748416321255;5759.4528519790765;5790.739416726236;5800.564957628174;5813.447722466359;5578.86052848678;5810.215620891167;5876.382979787125;5888.087346461305;5894.056950887837;5134.17853143499;5163.044836705221;5166.457521960216;5171.198464238049;5174.068667639824;24493.63259551108;24627.38431539208;25656.61515077275;25656.973721051134;25785.097521586653;2129.4995676016124;2176.412368660453;2828.9292767680768;2842.2325971987652;2947.6168098147105;14191.432342706279;14194.21931793751;14564.854351456455;15102.924485377504;15304.770428443582;1699;1719.1576127697338;1835.908204589765;1844.8708590398678;1847.9630407391912;91067;108865;114381.42809285983;114566;116904;11269.21115521922;11578.363190024462;11590.420478976017;11631.65105046833;11714.76570468603;14645.853541464661;14788.334524946404;14812.185329806964;14812.70374592502;14832.851671483148;7282.344588987016;7563.508371955805;8047.315978141806;8076.313513351431;8526.87209691855;8239.876401853977;8306.293965013041;8307.210814972568;8428;8437.746867593982;4447.466304043535;4563.315502674625;4563.566461186182;4613.469451013109;4624.219675424918;1902.220089763218;1953.3358658056045;1954.90225488725;1955.3742802303411;1958.1090603775249;866.8049688820091;908.9909100908907;933.7012156109979;953.270747877874;977.0669011094529;19274;19911.701324480146;20329.288474903602;20628.69056964147;20906.581868362548;939.1641439119189;957.2246480350938;979.6228452045871;1001.6794625719846;1029.6756521695643;10729.517171727291;10804.513796879159;10812.513436895359;10873.456327183609;11003.954624310678;621138;621455.2881662359;633920;642910;643996;120181;124349.37825310815;124700.37649811692;125269;125808;1047.0628787235382;1073.7691396349821;1076.235872530512;1108.41808050774;1109.0739232740752;17683.646327073642;17956.91021544884;18067.367642132464;18080.09599520045;18358.81641183571;43253.13493730171;49614.00771984612;49644.00711985812;50604;51237.74381128071;75453.24546754682;75826;75852;76722;78577.214227857;6887.827804304932;7975.920240797518;8039.316658083894;8189.262966333059;8438.53588052663;9448;11072.723181920193;11890.28658280506;12706.491740330654;13167;8788.209061184523;10174.694759157237;10748.301360411548;10964.616238431616;11228.101751859995;10849.132069434585;10979.560817567208;10985.560577576807;11133.55465781392;11171.832422513506;11272.436378180893;11359.54561817551;11608.071354291806;11651.41742912868;11754;7981.2018798119725;8111.1888811118415;8247.95876020616;8294.336453083859;8590.957045214735;8348.206920342558;10584.206184536037;10883.183761218099;11026.283291586022;11195.832062518906 09:56:42 INFO - PROCESS | 1670 | |3;mootools.html;1434.8134742483476;1611.041430348942;1616.8787340949427;1844.7417361569373;1953;7922.722704705337;8490.6179221935;8666.436681615707;8674.739757807276;8696.478211307325;8296.04595471521;8560.27237684796;8563.143685631434;8570.485770853753;8578.313734901209;2158.881261530615;2318.0959425823944;2357.174988753931;2382.0828980842416;2458.188797696756;2478.529079474894;2504.887280072401;2506.5362907862013;2507.523570521604;2509.4353770401603;2421.806255499556;2482.4786794773117;2483.044028049196;2485.2171565956755;2488.776010159085;2158.179891641174;2398.940026499334;2467.4324905271797;2467.938301542458;2468.3462080405993;2167.2306331252335;2397.712274527068;2469.1358024691394;2486.639437281602;2487.3657217409627;8646.956765216197;8721.21509064181;8743.300535957109;8745.606447709868;8753.08092650269;5218.921716174261;5231.2937753403185;5415.377231618375;5420.322459692538;5434.538064264542;3328.933421331584;3356.5636467259346;3392.4677898909813;3428.0915557377316;3439.501272315525;937.6952490440634;955.5795450001976;956.0344052506948;969.3699095587924;974.4056125763278;10188.54151563174;12093.87906120945;12107.213031153035;12123.030157587373;12133.514659413613;4761.571458568746;4883.4139903211135;4943.036107958914;4951.133551628451;4987.226979818134;10065.245106617032;10186.541605627765;10199.235057370726;10200.642977495752;10205.74485637865;5471.206675032098;5479.835604931858;5484.533814625722;5486.615936884459;5494.917576236279;4475.820967161277;4661.836835710802;4842.273658951185;4948.975255123702;4957.330760347307;3428.3143371325837;3857.9807100964317;3977.2642061218767;4011.5787842276723;4026.4966879140106;1326.2042774335514;1351.2770667692737;1352.377906163176;1368.5962641020922;1368.9041767076408;824.5258976088772;868.2489646455771;892.3530440430636;911.9544022798833;951.6241084771476;5590.636608620427;5669.971650141723;5843.94156058434;5887.499562537232;5966.653710846081;917.3074328881785;922.1285884838898;959.5490119643756;969.5685419988038;1014.974625634365;3272.5582046423906;3800.524934383202;3890.3386424307937;3939.428782826474;3970.722049456568;9213;10475;10817.729556761144;11017.779644407066;11052.944735276433;10669.09312708413;10745.78508429827;10813.243072984815;10876.347419154717;10877.347359158475;8210.794730131674;8355.749327520183;8466.449680770607;8482;8496.957515212385;5739.339975902658;5761.596688231783;5790.507806836466;5815.79644712433;5996;10056.044675755791;10132;10307.896921030693;10327.277090603886;10423.583056677951;10191.439470829175;10449.9477502612;10516.158707303244;10531.94734026325;10721.8556288741;5797.507211886868;6163.3836616338;7075.363217310467;7121.67952442131;7322.707091716485;8287.419880608299;8319.625616847135;8422.24199822019;8432.957835210786;8444.408891377545;7289.635518224173;7369.926300736924;7379.3358597726465;7505.88741168872;7507.737229197171;7246.6376681166785;7280.599567023864;7284.053073100675;7365.926340736738;7416.629168541659;6876.449884009367;6939.063226464363;6975.162980442379;6981.720731170696;6986.371226589631;4821.5178482152905;4847.321375007572;4887.34020907173;4980.103581355246;5105.948940510547;4864.197407427876;4891.364122664031;4973.950260497349;5046.268291097844;5176.585873130216 09:56:42 INFO - PROCESS | 1670 | |4;prototype.html;274.722530244453;306.77452072726555;316.81782974789496;325.4418671977558;326.0983380951668;4387.7147985380925;4655.743934083628;5171.663841850277;5336.759845806938;5391.223519887261;4694.835680751175;4793.856184314467;5307.708076055816;5319.840404787863;5389.78440862365;3925.900747790623;4142.2958097123565;4158.833646654138;4175.31107367284;4188.560201178878;3929.4105884117325;3943.2507823513442;4137.213929353428;4179.749215047092;4209.557996410379;3463.3765922134007;3789.1663833956486;3968.325384684596;4118.608732170454;4121.690873184523;4097.303458412077;4109.712320137592;4122.876313710579;4124.835006599732;4156.2726522858375;3622.2936527377174;3634.854605815791;3635.4910312556262;3647.963520364789;3652.470391793175;4066.9593304066875;4743.857684269477;5066.518680725325;5152.974235128838;5258.237555554461;4668.976655116703;4717.386739723848;4723.858284251478;4741.928871066937;4773.162332501977;5510.194103554483;5539.944600554023;5637.802676906289;5637.943620563783;5641.73061061258;3783.1651648950237;3865.903352416184;3997;3999.2601368746873;4028.8992775180855;5193.039287731782;5637.46444087811;5638.407967163471;5830.300363956268;6007.519398448114;3390.6609339065894;3828.8085595720104;3864.652181303696;3918.647321741057;3932.901677458029;3625.3353784968026;3908.928268017955;4095.795210239476;4109.794510274474;4117.279476091732;3975.542812576533;4599.402077729874;4710.36410084641;4719.056188762262;4726.503717109723;4784.349399457182;5388.73056347189;5402.621816472888;5742.511886489611;5744.827655170351;1493;1516.673915108257;1518.764591488321;1520.7946927164912;1528.709545186411;994.8213412626325;1025.369397820335;1032.5766435761452;1035.3529044347283;1044.2794471814575;5372.167314066326;5790.768369265266;5808.912866307009;5825.825225243249;5833.620814647034;6083.178770865877;6139;6320.652364119926;6375.6174629522375;6411.230652321751;4783.210770222874;5184.637075404761;5292.311999440049;5528.253685752372;5801.274840644919;3398.5072164536004;3400.4219282721997;3413.385590593717;3421.203867969336;3434.4333185024193;6536.227270918667;6807.141000779685;6808.761693340709;6832.282610325844;6834.6582670866255;6085.695715214221;6436.613803171825;6674.599524028574;6681.7327306908155;6682.264950855402;6175.84560385994;6431.234569135317;6587.374199451045;6760.560563563353;6823.522353435212;4976.328195693535;6072;6385.8722825544155;6435.4529864962005;6490.3185165558825;5029.893423446836;5620.437956204347;6370.77702280418;6381.266154392119;6428.453581445629;5396.83809485716;6041.942660034564;6344.397282258178;6612.702428390638;6794.320567943166;3940.763554186758;4162.646175075152;4176.791160442027;4205.705600607928;4208.431861698754;5082.669626474268;5409.864753381197;5841.591088623925;6216.53375996793;6405.391487808651;5563.360213575491;5616.550675945996;5845.766169353178;6048.562255653427;6171.117809068277;5226.738663066755;6192.876142477216;6234.625922444487;6243.531735119975;6265.874682506415;5898.233229680114;6094.695265236809;6116.66358350295;6122.387761223842;6151.876962460815;4897.114610874768;5192.818251361185;6203;6243.8126856194385;6295.5907865988565;5110.824510362711;5236.250968241704;6068.302145253352;6074.149619053248;6117.204763380732 09:56:42 INFO - PROCESS | 1670 | |5;yui.html;741.9480636355454;742.676935533043;749.6326799868062;755.9168491465941;794.2732399854132;14408.71182576348;14485.710285794277;14972.475963341285;14997.700045999074;15096.622584435394;11683.707907302321;13956.2324072176;13968.790468142986;13975.580732578013;13995.580132596038;721.2318880392392;916.7478943290598;918.7060140754961;929.1173385283973;929.1962452478625;718.6406796601699;809.5345176523493;895.3463971300954;937.1612406895824;939.3096074385321;917.4403613795579;955.7323949294208;966.9613215471408;967.56459593183;968.1528662420383;753.4010461682975;912.2519533982137;942.0955882352949;959.9280053995943;965.4110992294694;716.9354758071798;725.166059032112;728.4536597551836;728.6720975560966;728.9708411663527;14823.629409264748;14952.925235373863;14954.476593319181;14955.252237388197;14958.252087395587;12560.43478043481;12680.68298292541;12781.680457988532;12964.740705185937;13001.479940802357;9974.451405172713;10088.445135517544;10159.746006349826;10182.014507171105;10850.620228291971;3457.2739724657854;3504.8072356020407;3535.734819888519;3540.805255710935;3616.150204701893;79264;80208;80704;80893.59553202255;81242.9999999994;9299.900695134062;9562.808743825084;9736.269779766546;9802.117809397188;9874.456904870158;18484.44546663602;22108.668369974464;22209.55580888373;22286.88856555707;22844.97197626111;7480.214577469287;7514.736984205636;7548.66031028605;7611.543307401574;7762.262585054411;3980.800959951991;4021.746706628589;4053.2704113259897;4111.1366613011005;4329.523752387235;3400.6089299730847;3486.877959271413;3493.5633045869267;3498.792916443823;3499.492573576868;594.1800315564493;604.4982664388547;607.8814631146884;613.8649497110629;625.1435533319291;536.4153073150285;563.4956713741178;598.9460948514654;607.6657838188978;609.6220343387129;6677.224458995892;8169.550674712829;8261.297789687824;8276.544790036485;8376.623051962677;568.7799669708452;587.2365924298481;594.9583529152918;609.7591451376682;614.2935624032309;3198.6321573018936;3258.283177700902;3840.0783811885503;3879.8254078566533;4010.136706956108;93614;93757.12485750247;94839.52580237055;99779;99860.5006974975;59653;76551.61724191344;76611;77725;78063.60968195123;68080;69211;69584.60830783357;69761.3023869765;70360.64819675869;8007.879881801894;8127.471714338549;8209.343252539664;8216.46592971455;8231;41875.115619796576;43734.56265437305;44030.779846100566;44360;44472.33291500695;55030.44969550253;62203.377966219756;63108;65843.37861417668;68144;8913;10665.253432259668;10713.250072494851;11189.720256993314;11493.252938558968;11696.528416291909;11713.531458741554;11814.645560633195;11829.822552661544;12394.132410731163;9955.701328960144;11315.151363647594;11590;11826.586069487526;11861.940690296493;9866.703998880044;11538.134639901873;11711.531538738354;11731.706707332385;11834.171607987359;11169.050630696482;11183.193394280328;11286.548538058385;11528.942355288169;11828.75253701794;9586.137247647466;9661.372010819274;9775.413475191512;9782.510874456106;10089.041541053588;8496.872546911925;9599.664011759554;9608.519574021411;9696.85454718194;9795.363301385387 09:56:42 INFO - PROCESS | 1670 | __end_tp_report 09:56:42 INFO - PROCESS | 1670 | __start_cc_report 09:56:42 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1303 09:56:42 INFO - PROCESS | 1670 | __end_cc_report 09:56:42 INFO - PROCESS | 1670 | __startTimestamp1461776202689__endTimestamp 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | ------- Summary: start ------- 09:56:42 INFO - PROCESS | 1670 | Number of tests: 6 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5599.49 Median:5757.40 stddev:752.52 (13.1%) stddev-sans-first:733.84 09:56:42 INFO - PROCESS | 1670 | Values: 3349.6 3404.2 3422.5 3439.5 3478.9 5508.3 5588.3 5594.0 5596.3 5636.2 5594.0 5726.2 5726.2 5741.2 5746.9 4326.8 5101.9 5139.7 5165.9 5199.8 331.3 5220.9 5231.7 5250.2 5293.4 5053.3 5134.6 5158.2 5205.8 5208.5 4742.7 5080.1 5150.3 5163.0 5182.2 5134.2 5144.5 5157.8 5170.3 5191.8 5410.2 5523.6 5533.8 5590.9 5639.1 5140.6 5998.7 6071.4 6170.7 6201.6 6145.2 6216.9 6219.9 6223.7 6233.6 5588.4 5673.1 5677.3 5680.2 5732.0 6203.5 6255.5 6276.2 6313.9 6316.8 5836.0 5843.5 5847.4 5857.1 5897.8 5358.4 5902.4 6081.4 6084.4 6103.8 5953.4 5994.0 6096.8 6101.3 6181.8 6128.8 6149.2 6172.1 6178.6 6181.6 4821.1 4825.6 4840.8 4879.7 4883.9 4287.1 4368.6 4380.3 4402.6 4438.4 5598.3 5599.9 5611.2 5612.5 5612.7 6101.8 6145.5 6152.6 6164.8 6166.8 5589.1 5654.6 5657.2 5705.7 5706.6 5320.5 5325.1 5334.8 5336.8 5342.5 6214.8 6242.5 6283.1 6292.4 6313.7 5267.0 6223.3 6257.9 6272.9 6279.8 6272.5 6297.1 6305.9 6306.6 6308.1 5978.3 5993.9 6003.2 6087.9 6123.0 4994.5 6128.1 6208.3 6232.3 6244.1 4974.3 6043.8 6049.4 6049.8 6054.6 5833.6 5969.6 6029.6 6044.2 6084.4 5845.2 5848.0 5852.5 5854.4 5855.1 4860.6 5975.2 6050.0 6051.2 6104.6 5665.9 5757.4 5790.0 5792.6 5802.9 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:53757.97 Median:3238.92 stddev:105765.78 (3265.5%) stddev-sans-first:105986.89 09:56:42 INFO - PROCESS | 1670 | Values: 499.5 525.2 539.1 564.5 588.7 28953.6 29524.6 36974.6 37929.0 38015.6 37273.8 40055.8 40160.4 40217.0 40281.2 8349.1 9275.9 9485.7 9486.5 9493.6 8402.2 11082.6 11283.7 11325.7 11335.9 11219.2 11352.3 11367.3 11380.6 11386.4 8279.6 8501.0 8511.4 11256.4 11343.2 8439.8 10996.5 11264.8 11277.7 11279.0 36038.1 36566.1 36647.0 36655.5 36731.4 3163.4 3189.6 3235.8 3242.0 3384.0 26216.3 26714.2 26948.7 27253.8 27859.9 2536.1 2567.8 2574.5 2587.3 2651.8 285189.0 285665.0 286272.0 286277.0 345541.0 56459.0 56660.4 56684.0 56806.0 56820.9 59561.7 60341.1 60611.0 60667.7 60743.0 16053.4 16269.8 16270.5 16466.3 16642.7 11337.3 11429.6 12427.4 12541.5 12558.2 18410.4 18421.0 18473.4 18477.2 18514.1 6669.3 7637.4 7715.0 7788.9 7824.5 973.8 974.1 975.9 1078.8 1136.4 27736.0 27929.4 27980.9 28894.9 28940.6 1051.3 1070.6 1077.1 1090.3 1111.9 13942.7 14099.2 14419.7 14445.3 14614.9 481625.0 482968.0 483269.0 487229.0 487961.0 54216.5 54574.2 54824.0 55247.0 55247.4 1348.2 1451.9 1493.5 1496.9 1512.4 26812.2 27135.7 27738.4 27742.3 27780.2 47504.5 48015.8 48775.3 48805.3 48840.8 388771.0 390617.0 392182.0 392318.0 393336.0 21453.8 24054.5 24557.9 24808.4 25250.6 38771.0 39028.6 39938.6 40024.0 40674.6 32752.5 33335.2 33435.7 33952.8 34176.0 32665.0 33127.7 33181.3 33404.2 33713.7 34286.5 34521.8 34734.8 35114.6 35116.5 29146.0 29664.6 30753.1 30781.7 30910.9 17921.2 23050.7 23321.3 23500.6 24452.9 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:35388.14 Median:24560.51 stddev:105012.16 (427.6%) stddev-sans-first:105273.86 09:56:42 INFO - PROCESS | 1670 | Values: 388.6 391.8 392.2 392.3 399.8 13644.1 19796.6 19814.7 20603.0 20649.8 13865.2 18283.4 19054.7 19747.8 20555.0 5696.4 5862.0 5895.1 5901.1 5906.5 5830.5 5884.0 5938.4 6027.7 6031.5 5590.7 5759.5 5790.7 5800.6 5813.4 5578.9 5810.2 5876.4 5888.1 5894.1 5134.2 5163.0 5166.5 5171.2 5174.1 24493.6 24627.4 25656.6 25657.0 25785.1 2129.5 2176.4 2828.9 2842.2 2947.6 14191.4 14194.2 14564.9 15102.9 15304.8 1699.0 1719.2 1835.9 1844.9 1848.0 91067.0 108865.0 114381.4 114566.0 116904.0 11269.2 11578.4 11590.4 11631.7 11714.8 14645.9 14788.3 14812.2 14812.7 14832.9 7282.3 7563.5 8047.3 8076.3 8526.9 8239.9 8306.3 8307.2 8428.0 8437.7 4447.5 4563.3 4563.6 4613.5 4624.2 1902.2 1953.3 1954.9 1955.4 1958.1 866.8 909.0 933.7 953.3 977.1 19274.0 19911.7 20329.3 20628.7 20906.6 939.2 957.2 979.6 1001.7 1029.7 10729.5 10804.5 10812.5 10873.5 11004.0 621138.0 621455.3 633920.0 642910.0 643996.0 120181.0 124349.4 124700.4 125269.0 125808.0 1047.1 1073.8 1076.2 1108.4 1109.1 17683.6 17956.9 18067.4 18080.1 18358.8 43253.1 49614.0 49644.0 50604.0 51237.7 75453.2 75826.0 75852.0 76722.0 78577.2 6887.8 7975.9 8039.3 8189.3 8438.5 9448.0 11072.7 11890.3 12706.5 13167.0 8788.2 10174.7 10748.3 10964.6 11228.1 10849.1 10979.6 10985.6 11133.6 11171.8 11272.4 11359.5 11608.1 11651.4 11754.0 7981.2 8111.2 8248.0 8294.3 8591.0 8348.2 10584.2 10883.2 11026.3 11195.8 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5718.03 Median:4946.01 stddev:3216.90 (65.0%) stddev-sans-first:3209.82 09:56:42 INFO - PROCESS | 1670 | Values: 1434.8 1611.0 1616.9 1844.7 1953.0 7922.7 8490.6 8666.4 8674.7 8696.5 8296.0 8560.3 8563.1 8570.5 8578.3 2158.9 2318.1 2357.2 2382.1 2458.2 2478.5 2504.9 2506.5 2507.5 2509.4 2421.8 2482.5 2483.0 2485.2 2488.8 2158.2 2398.9 2467.4 2467.9 2468.3 2167.2 2397.7 2469.1 2486.6 2487.4 8647.0 8721.2 8743.3 8745.6 8753.1 5218.9 5231.3 5415.4 5420.3 5434.5 3328.9 3356.6 3392.5 3428.1 3439.5 937.7 955.6 956.0 969.4 974.4 10188.5 12093.9 12107.2 12123.0 12133.5 4761.6 4883.4 4943.0 4951.1 4987.2 10065.2 10186.5 10199.2 10200.6 10205.7 5471.2 5479.8 5484.5 5486.6 5494.9 4475.8 4661.8 4842.3 4949.0 4957.3 3428.3 3858.0 3977.3 4011.6 4026.5 1326.2 1351.3 1352.4 1368.6 1368.9 824.5 868.2 892.4 912.0 951.6 5590.6 5670.0 5843.9 5887.5 5966.7 917.3 922.1 959.5 969.6 1015.0 3272.6 3800.5 3890.3 3939.4 3970.7 9213.0 10475.0 10817.7 11017.8 11052.9 10669.1 10745.8 10813.2 10876.3 10877.3 8210.8 8355.7 8466.4 8482.0 8497.0 5739.3 5761.6 5790.5 5815.8 5996.0 10056.0 10132.0 10307.9 10327.3 10423.6 10191.4 10449.9 10516.2 10531.9 10721.9 5797.5 6163.4 7075.4 7121.7 7322.7 8287.4 8319.6 8422.2 8433.0 8444.4 7289.6 7369.9 7379.3 7505.9 7507.7 7246.6 7280.6 7284.1 7365.9 7416.6 6876.4 6939.1 6975.2 6981.7 6986.4 4821.5 4847.3 4887.3 4980.1 5105.9 4864.2 4891.4 4974.0 5046.3 5176.6 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4819.19 Median:5188.73 stddev:1530.39 (29.5%) stddev-sans-first:1496.19 09:56:42 INFO - PROCESS | 1670 | Values: 274.7 306.8 316.8 325.4 326.1 4387.7 4655.7 5171.7 5336.8 5391.2 4694.8 4793.9 5307.7 5319.8 5389.8 3925.9 4142.3 4158.8 4175.3 4188.6 3929.4 3943.3 4137.2 4179.7 4209.6 3463.4 3789.2 3968.3 4118.6 4121.7 4097.3 4109.7 4122.9 4124.8 4156.3 3622.3 3634.9 3635.5 3648.0 3652.5 4067.0 4743.9 5066.5 5153.0 5258.2 4669.0 4717.4 4723.9 4741.9 4773.2 5510.2 5539.9 5637.8 5637.9 5641.7 3783.2 3865.9 3997.0 3999.3 4028.9 5193.0 5637.5 5638.4 5830.3 6007.5 3390.7 3828.8 3864.7 3918.6 3932.9 3625.3 3908.9 4095.8 4109.8 4117.3 3975.5 4599.4 4710.4 4719.1 4726.5 4784.3 5388.7 5402.6 5742.5 5744.8 1493.0 1516.7 1518.8 1520.8 1528.7 994.8 1025.4 1032.6 1035.4 1044.3 5372.2 5790.8 5808.9 5825.8 5833.6 6083.2 6139.0 6320.7 6375.6 6411.2 4783.2 5184.6 5292.3 5528.3 5801.3 3398.5 3400.4 3413.4 3421.2 3434.4 6536.2 6807.1 6808.8 6832.3 6834.7 6085.7 6436.6 6674.6 6681.7 6682.3 6175.8 6431.2 6587.4 6760.6 6823.5 4976.3 6072.0 6385.9 6435.5 6490.3 5029.9 5620.4 6370.8 6381.3 6428.5 5396.8 6041.9 6344.4 6612.7 6794.3 3940.8 4162.6 4176.8 4205.7 4208.4 5082.7 5409.9 5841.6 6216.5 6405.4 5563.4 5616.6 5845.8 6048.6 6171.1 5226.7 6192.9 6234.6 6243.5 6265.9 5898.2 6094.7 6116.7 6122.4 6151.9 4897.1 5192.8 6203.0 6243.8 6295.6 5110.8 5236.3 6068.3 6074.1 6117.2 09:56:42 INFO - PROCESS | 1670 | 09:56:42 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:17919.51 Median:611.81 stddev:25305.03 (4136.1%) stddev-sans-first:25343.14 09:56:42 INFO - PROCESS | 1670 | Values: 741.9 742.7 749.6 755.9 794.3 14408.7 14485.7 14972.5 14997.7 15096.6 11683.7 13956.2 13968.8 13975.6 13995.6 721.2 916.7 918.7 929.1 929.2 718.6 809.5 895.3 937.2 939.3 917.4 955.7 967.0 967.6 968.2 753.4 912.3 942.1 959.9 965.4 716.9 725.2 728.5 728.7 729.0 14823.6 14952.9 14954.5 14955.3 14958.3 12560.4 12680.7 12781.7 12964.7 13001.5 9974.5 10088.4 10159.7 10182.0 10850.6 3457.3 3504.8 3535.7 3540.8 3616.2 79264.0 80208.0 80704.0 80893.6 81243.0 9299.9 9562.8 9736.3 9802.1 9874.5 18484.4 22108.7 22209.6 22286.9 22845.0 7480.2 7514.7 7548.7 7611.5 7762.3 3980.8 4021.7 4053.3 4111.1 4329.5 3400.6 3486.9 3493.6 3498.8 3499.5 594.2 604.5 607.9 613.9 625.1 536.4 563.5 598.9 607.7 609.6 6677.2 8169.6 8261.3 8276.5 8376.6 568.8 587.2 595.0 609.8 614.3 3198.6 3258.3 3840.1 3879.8 4010.1 93614.0 93757.1 94839.5 99779.0 99860.5 59653.0 76551.6 76611.0 77725.0 78063.6 68080.0 69211.0 69584.6 69761.3 70360.6 8007.9 8127.5 8209.3 8216.5 8231.0 41875.1 43734.6 44030.8 44360.0 44472.3 55030.4 62203.4 63108.0 65843.4 68144.0 8913.0 10665.3 10713.3 11189.7 11493.3 11696.5 11713.5 11814.6 11829.8 12394.1 9955.7 11315.2 11590.0 11826.6 11861.9 9866.7 11538.1 11711.5 11731.7 11834.2 11169.1 11183.2 11286.5 11528.9 11828.8 9586.1 9661.4 9775.4 9782.5 10089.0 8496.9 9599.7 9608.5 9696.9 9795.4 09:56:42 INFO - PROCESS | 1670 | -------- Summary: end -------- 09:56:42 INFO - PROCESS | 1670 | 09:56:43 INFO - TEST-INFO | 1670: exit 0 09:56:43 INFO - TEST-OK | dromaeo_css | took 1114493ms 09:56:43 INFO - TEST-START | kraken 09:56:43 INFO - operating with platform_type : mac_ 09:56:43 INFO - Initialising browser for kraken test... 09:56:43 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpA0vulZ/profile http://localhost:49275/getInfo.html) 09:56:45 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 09:56:45 INFO - PROCESS | 1677 | colorDepth:24 09:56:45 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 09:56:45 INFO - PROCESS | 1677 | __metrics 09:56:45 INFO - PROCESS | 1677 | 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" 09:56:45 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:56:46 INFO - TEST-INFO | 1677: exit 0 09:56:46 INFO - Browser initialized. 09:56:46 INFO - Running cycle 1/1 for kraken test... 09:56:46 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpA0vulZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:58 INFO - PROCESS | 1679 | RSS: Main: 239386624 09:56:58 INFO - PROCESS | 1679 | 09:57:00 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:01 INFO - PROCESS | 1679 | RSS: Main: 286134272 09:57:01 INFO - PROCESS | 1679 | 09:57:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:57:05 INFO - PROCESS | 1679 | RSS: Main: 299311104 09:57:05 INFO - PROCESS | 1679 | 09:57:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:57:09 INFO - PROCESS | 1679 | RSS: Main: 387645440 09:57:09 INFO - PROCESS | 1679 | 09:57:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:57:11 INFO - PROCESS | 1679 | RSS: Main: 287178752 09:57:11 INFO - PROCESS | 1679 | 09:57:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:14 INFO - PROCESS | 1679 | RSS: Main: 290783232 09:57:14 INFO - PROCESS | 1679 | 09:57:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:17 INFO - PROCESS | 1679 | RSS: Main: 329613312 09:57:17 INFO - PROCESS | 1679 | 09:57:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:21 INFO - PROCESS | 1679 | RSS: Main: 328593408 09:57:21 INFO - PROCESS | 1679 | 09:57:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:57:24 INFO - PROCESS | 1679 | RSS: Main: 328802304 09:57:24 INFO - PROCESS | 1679 | 09:57:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:27 INFO - PROCESS | 1679 | RSS: Main: 321298432 09:57:27 INFO - PROCESS | 1679 | 09:57:28 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:29 INFO - PROCESS | 1679 | RSS: Main: 328081408 09:57:29 INFO - PROCESS | 1679 | 09:57:31 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:32 INFO - PROCESS | 1679 | RSS: Main: 329728000 09:57:32 INFO - PROCESS | 1679 | 09:57:35 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:35 INFO - PROCESS | 1679 | RSS: Main: 334426112 09:57:35 INFO - PROCESS | 1679 | 09:57:39 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:39 INFO - PROCESS | 1679 | RSS: Main: 332488704 09:57:39 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:57:41 INFO - PROCESS | 1679 | RSS: Main: 396644352 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | __start_tp_report 09:57:41 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 09:57:41 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 09:57:41 INFO - PROCESS | 1679 | |i|pagename|runs| 09:57:41 INFO - PROCESS | 1679 | |0;ai-astar;111;110;109;112;111;113;109;112;107;110 09:57:41 INFO - PROCESS | 1679 | |1;audio-beat-detection;183;149;173;168;120;144;140;118;170;120 09:57:41 INFO - PROCESS | 1679 | |2;audio-dft;186;190;183;184;194;221;185;184;181;184 09:57:41 INFO - PROCESS | 1679 | |3;audio-fft;77;72;74;149;56;57;58;64;128;55 09:57:41 INFO - PROCESS | 1679 | |4;audio-oscillator;90;93;134;77;81;84;122;80;77;130 09:57:41 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;90;125;89;87;88;86;90;88;88;83 09:57:41 INFO - PROCESS | 1679 | |6;imaging-darkroom;92;101;92;93;92;93;92;93;92;109 09:57:41 INFO - PROCESS | 1679 | |7;imaging-desaturate;85;84;86;87;84;84;86;84;86;83 09:57:41 INFO - PROCESS | 1679 | |8;json-parse-financial;58;60;58;59;60;59;57;77;60;59 09:57:41 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;61;65;69;66;77;71;71;67;67;67 09:57:41 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;81;96;81;82;68;96;106;105;106;82 09:57:41 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;141;133;129;157;156;155;140;137;179;144 09:57:41 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;197;195;200;196;205;197;200;199;199;195 09:57:41 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;70;69;71;70;70;70;71;70;69;69 09:57:41 INFO - PROCESS | 1679 | __end_tp_report 09:57:41 INFO - PROCESS | 1679 | __start_cc_report 09:57:41 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2003 09:57:41 INFO - PROCESS | 1679 | __end_cc_report 09:57:41 INFO - PROCESS | 1679 | __startTimestamp1461776261721__endTimestamp 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | ------- Summary: start ------- 09:57:41 INFO - PROCESS | 1679 | Number of tests: 14 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.78 (1.6%) stddev-sans-first:1.87 09:57:41 INFO - PROCESS | 1679 | Values: 111.0 110.0 109.0 112.0 111.0 113.0 109.0 112.0 107.0 110.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:158.50 stddev:24.22 (15.3%) stddev-sans-first:22.24 09:57:41 INFO - PROCESS | 1679 | Values: 183.0 149.0 173.0 168.0 120.0 144.0 140.0 118.0 170.0 120.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:189.20 Median:185.50 stddev:11.78 (6.4%) stddev-sans-first:12.44 09:57:41 INFO - PROCESS | 1679 | Values: 186.0 190.0 183.0 184.0 194.0 221.0 185.0 184.0 181.0 184.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:79.00 Median:61.00 stddev:32.72 (53.6%) stddev-sans-first:34.69 09:57:41 INFO - PROCESS | 1679 | Values: 77.0 72.0 74.0 149.0 56.0 57.0 58.0 64.0 128.0 55.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:80.50 stddev:22.76 (28.3%) stddev-sans-first:24.01 09:57:41 INFO - PROCESS | 1679 | Values: 90.0 93.0 134.0 77.0 81.0 84.0 122.0 80.0 77.0 130.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:88.00 stddev:11.98 (13.6%) stddev-sans-first:12.70 09:57:41 INFO - PROCESS | 1679 | Values: 90.0 125.0 89.0 87.0 88.0 86.0 90.0 88.0 88.0 83.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:92.00 stddev:5.67 (6.2%) stddev-sans-first:5.91 09:57:41 INFO - PROCESS | 1679 | Values: 92.0 101.0 92.0 93.0 92.0 93.0 92.0 93.0 92.0 109.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.36 09:57:41 INFO - PROCESS | 1679 | Values: 85.0 84.0 86.0 87.0 84.0 84.0 86.0 84.0 86.0 83.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.50 stddev:5.81 (9.8%) stddev-sans-first:6.08 09:57:41 INFO - PROCESS | 1679 | Values: 58.0 60.0 58.0 59.0 60.0 59.0 57.0 77.0 60.0 59.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:4.28 (6.3%) stddev-sans-first:3.69 09:57:41 INFO - PROCESS | 1679 | Values: 61.0 65.0 69.0 66.0 77.0 71.0 71.0 67.0 67.0 67.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:81.50 stddev:13.26 (16.3%) stddev-sans-first:13.63 09:57:41 INFO - PROCESS | 1679 | Values: 81.0 96.0 81.0 82.0 68.0 96.0 106.0 105.0 106.0 82.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:149.50 stddev:14.84 (9.9%) stddev-sans-first:15.58 09:57:41 INFO - PROCESS | 1679 | Values: 141.0 133.0 129.0 157.0 156.0 155.0 140.0 137.0 179.0 144.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.30 Median:199.00 stddev:3.02 (1.5%) stddev-sans-first:3.17 09:57:41 INFO - PROCESS | 1679 | Values: 197.0 195.0 200.0 196.0 205.0 197.0 200.0 199.0 199.0 195.0 09:57:41 INFO - PROCESS | 1679 | 09:57:41 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 09:57:41 INFO - PROCESS | 1679 | Values: 70.0 69.0 71.0 70.0 70.0 70.0 71.0 70.0 69.0 69.0 09:57:41 INFO - PROCESS | 1679 | -------- Summary: end -------- 09:57:41 INFO - PROCESS | 1679 | 09:57:42 INFO - TEST-INFO | 1679: exit 0 09:57:42 INFO - TEST-OK | kraken | took 59189ms 09:57:42 INFO - SUITE-END | took 1173s 09:57:42 INFO - Completed test suite (00:19:34) 09:57:42 INFO - javascript benchmark 09:57:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3349.5980482342115, 3404.2340473393474, 3422.520847081408, 3439.484077388393, 3478.930421391572, 5508.338999320087, 5588.30146231721, 5594.049011668016, 5596.27248457701, 5636.239107720457, 5594.0, 5726.1697053927155, 5726.226959360485, 5741.224934633823, 5746.885062298751, 4326.783660816946, 5101.897962040757, 5139.743012849362, 5165.870853228662, 5199.818006369778, 331.3179308023432, 5220.92168617471, 5231.686098834082, 5250.159974404096, 5293.3647962244395, 5053.267276244942, 5134.614903882224, 5158.200478925773, 5205.817796377109, 5208.531232189102, 4742.715437073752, 5080.085584594772, 5150.27896094547, 5163.0190263849745, 5182.222666600002, 5134.204198349262, 5144.485551444825, 5157.767900444489, 5170.25031370453, 5191.844244672666, 5410.188471729232, 5523.613347065707, 5533.750981205855, 5590.86022849428, 5639.125935480007, 5140.640155189139, 5998.730057147395, 6071.355617224624, 6170.691465426711, 6201.596896201778, 6145.201123853871, 6216.937830621681, 6219.880421524123, 6223.68881555925, 6233.563650544464, 5588.413216612278, 5673.063944449161, 5677.290338707661, 5680.233168522238, 5731.971340143315, 6203.534734894811, 6255.530835187288, 6276.152719382916, 6313.873722525524, 6316.842078948063, 5835.9708201458725, 5843.5325173985975, 5847.386024467455, 5857.062869940789, 5897.823065308047, 5358.356997160287, 5902.439268269508, 6081.391860813972, 6084.361142080106, 6103.755849766048, 5953.404659534012, 5994.0, 6096.817095487142, 6101.298350689727, 6181.783637572664, 6128.80869161996, 6149.169862068564, 6172.135900973867, 6178.567500274938, 6181.629102253879, 4821.132196204647, 4825.638077144228, 4840.806367745321, 4879.682820616648, 4883.949950760603, 4287.099709061132, 4368.584984426538, 4380.3210502372185, 4402.581754733295, 4438.400815889813, 5598.300212473441, 5599.860003499945, 5611.242482264924, 5612.522935550524, 5612.663240205601, 6101.792513241593, 6145.508359331243, 6152.600080994721, 6164.753409863645, 6166.845828854225, 5589.077802162597, 5654.575906806924, 5657.179708942181, 5705.657660540381, 5706.572007099401, 5320.467953204726, 5325.121354976463, 5334.813281535127, 5336.759845806948, 5342.492463215988, 6214.751409943642, 6242.500599952084, 6283.120363149163, 6292.433680968735, 6313.715882785285, 5266.999270138662, 6223.3465486125115, 6257.906131408124, 6272.905906411499, 6279.843003924939, 6272.529560283088, 6297.118403423433, 6305.936940630535, 6306.621602703853, 6308.053791931247, 5978.252718410199, 5993.940060599338, 6003.219581454383, 6087.908681369871, 6123.020316749299, 4994.500549944976, 6128.142060111499, 6208.255009398901, 6232.345603711545, 6244.125822384779, 4974.253861920741, 6043.8186854394435, 6049.3950604939155, 6049.848753781014, 6054.606450580698, 5833.591648584558, 5969.552283578662, 6029.638221706711, 6044.24446944132, 6084.39156084388, 5845.240118784531, 5848.005839007294, 5852.502537284208, 5854.443827836349, 5855.063189889597, 4860.635452341159, 5975.223220981245, 6049.969750151221, 6051.152838602511, 6104.633721976696, 5665.915011274752, 5757.395473475225, 5789.9710501447225, 5792.594518383673, 5802.883942321215], "value": 5557.033082142291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.45309885675186, 525.2200482283805, 539.107776629678, 564.5004171308391, 588.6850534963795, 28953.565696514543, 29524.557131643014, 36974.630253697454, 37929.0, 38015.619843801556, 37273.81363093187, 40055.79972100136, 40160.39759403611, 40216.994575135635, 40281.19437611246, 8349.123342049088, 9275.907240927589, 9485.715428537154, 9486.478243696594, 9493.572789224467, 8402.201790829862, 11082.6121085762, 11283.717907052307, 11325.660230193067, 11335.886641133608, 11219.214654974156, 11352.262102963323, 11367.317960922372, 11380.601678941239, 11386.430678466086, 8279.62741676626, 8500.957495212484, 8511.404201705822, 11256.437178141101, 11343.205975581672, 8439.789005274917, 10996.505157267862, 11264.774704505946, 11277.718057048558, 11278.984891359734, 36038.099047523756, 36566.08584785375, 36647.0, 36655.450168247495, 36731.4490282646, 3163.43058249515, 3189.599563340441, 3235.822029788361, 3242.01118658809, 3383.9830800846084, 26216.344591385176, 26714.19857404281, 26948.73051269482, 27253.77358018894, 27859.86070069657, 2536.1377131775284, 2567.768900798937, 2574.45936353366, 2587.262630150417, 2651.814372993891, 285189.0, 285665.0, 286272.0, 286277.0, 345541.0, 56459.0, 56660.43339566552, 56684.0, 56806.0, 56820.86358272894, 59561.70219148963, 60341.094883576785, 60611.0, 60667.69666151729, 60743.0, 16053.43812966564, 16269.779766517322, 16270.51188464348, 16466.341346346013, 16642.66714665724, 11337.319760814378, 11429.59996400122, 12427.37863106841, 12541.498340066477, 12558.183718058297, 18410.35563755262, 18421.0, 18473.44579662612, 18477.228415737747, 18514.074296285133, 6669.333066693292, 7637.350825179811, 7714.961425192951, 7788.870613760973, 7824.530528168328, 973.756560859785, 974.0648976982035, 975.9024097590326, 1078.7840789781337, 1136.431784107946, 27736.02923897654, 27929.44141117166, 27980.86009569939, 28894.855525722236, 28940.565891511644, 1051.3008849115254, 1070.6145787516489, 1077.1329080090602, 1090.2640717515644, 1111.8943700348455, 13942.651433714036, 14099.224542650252, 14419.711605768036, 14445.349959251858, 14614.853851461561, 481625.0, 482968.0, 483269.0, 487229.0, 487961.0, 54216.45783542193, 54574.18138727922, 54824.0, 55247.0, 55247.447525525036, 1348.211296391622, 1451.9419223230952, 1493.4698182145182, 1496.9027013244104, 1512.3950419832156, 26812.224454907857, 27135.72864271411, 27738.44523109567, 27742.306442339104, 27780.16659500218, 47504.524954751396, 48015.75992120017, 48775.268370973754, 48805.26792098051, 48840.755796220794, 388771.0, 390617.04691476363, 392182.0, 392318.0, 393336.0, 21453.785462145177, 24054.518909621358, 24557.87721061383, 24808.379790505383, 25250.621240681037, 38771.0, 39028.6097139025, 39938.60061399349, 40024.0, 40674.593254067084, 32752.50871236981, 33335.16662083467, 33435.66564334326, 33952.83023584965, 34176.0, 32665.0, 33127.66872331342, 33181.336373271915, 33404.164895877795, 33713.66286337203, 34286.48570271398, 34521.82739086288, 34734.826325868205, 35114.648853511135, 35116.473252900716, 29145.979890703722, 29664.55503167411, 30753.077407677807, 30781.69218307788, 30910.918117865767, 17921.19354629071, 23050.654240186745, 23321.300360989197, 23500.647490288, 24452.877735611208], "value": 18532.788772073494, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.5861557441325, 391.77179542135394, 392.1764294980541, 392.2958289869684, 399.82407740594135, 13644.146183063387, 19796.604067918634, 19814.70277945832, 20603.0, 20649.761014339132, 13865.237411942338, 18283.360082397117, 19054.714179287323, 19747.802521974776, 20555.0, 5696.401877802833, 5862.032764593837, 5895.056790913454, 5901.144334071557, 5906.497947674453, 5830.533557315404, 5883.970580147094, 5938.376470470603, 6027.668478233695, 6031.517478601701, 5590.748416321255, 5759.4528519790765, 5790.739416726236, 5800.564957628174, 5813.447722466359, 5578.86052848678, 5810.215620891167, 5876.382979787125, 5888.087346461305, 5894.056950887837, 5134.17853143499, 5163.044836705221, 5166.457521960216, 5171.198464238049, 5174.068667639824, 24493.63259551108, 24627.38431539208, 25656.61515077275, 25656.973721051134, 25785.097521586653, 2129.4995676016124, 2176.412368660453, 2828.9292767680768, 2842.2325971987652, 2947.6168098147105, 14191.432342706279, 14194.21931793751, 14564.854351456455, 15102.924485377504, 15304.770428443582, 1699.0, 1719.1576127697338, 1835.908204589765, 1844.8708590398678, 1847.9630407391912, 91067.0, 108865.0, 114381.42809285983, 114566.0, 116904.0, 11269.21115521922, 11578.363190024462, 11590.420478976017, 11631.65105046833, 11714.76570468603, 14645.853541464661, 14788.334524946404, 14812.185329806964, 14812.70374592502, 14832.851671483148, 7282.344588987016, 7563.508371955805, 8047.315978141806, 8076.313513351431, 8526.87209691855, 8239.876401853977, 8306.293965013041, 8307.210814972568, 8428.0, 8437.746867593982, 4447.466304043535, 4563.315502674625, 4563.566461186182, 4613.469451013109, 4624.219675424918, 1902.220089763218, 1953.3358658056045, 1954.90225488725, 1955.3742802303411, 1958.1090603775249, 866.8049688820091, 908.9909100908907, 933.7012156109979, 953.270747877874, 977.0669011094529, 19274.0, 19911.701324480146, 20329.288474903602, 20628.69056964147, 20906.581868362548, 939.1641439119189, 957.2246480350938, 979.6228452045871, 1001.6794625719846, 1029.6756521695643, 10729.517171727291, 10804.513796879159, 10812.513436895359, 10873.456327183609, 11003.954624310678, 621138.0, 621455.2881662359, 633920.0, 642910.0, 643996.0, 120181.0, 124349.37825310815, 124700.37649811692, 125269.0, 125808.0, 1047.0628787235382, 1073.7691396349821, 1076.235872530512, 1108.41808050774, 1109.0739232740752, 17683.646327073642, 17956.91021544884, 18067.367642132464, 18080.09599520045, 18358.81641183571, 43253.13493730171, 49614.00771984612, 49644.00711985812, 50604.0, 51237.74381128071, 75453.24546754682, 75826.0, 75852.0, 76722.0, 78577.214227857, 6887.827804304932, 7975.920240797518, 8039.316658083894, 8189.262966333059, 8438.53588052663, 9448.0, 11072.723181920193, 11890.28658280506, 12706.491740330654, 13167.0, 8788.209061184523, 10174.694759157237, 10748.301360411548, 10964.616238431616, 11228.101751859995, 10849.132069434585, 10979.560817567208, 10985.560577576807, 11133.55465781392, 11171.832422513506, 11272.436378180893, 11359.54561817551, 11608.071354291806, 11651.41742912868, 11754.0, 7981.2018798119725, 8111.1888811118415, 8247.95876020616, 8294.336453083859, 8590.957045214735, 8348.206920342558, 10584.206184536037, 10883.183761218099, 11026.283291586022, 11195.832062518906], "value": 9538.839831082307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.8134742483476, 1611.041430348942, 1616.8787340949427, 1844.7417361569373, 1953.0, 7922.722704705337, 8490.6179221935, 8666.436681615707, 8674.739757807276, 8696.478211307325, 8296.04595471521, 8560.27237684796, 8563.143685631434, 8570.485770853753, 8578.313734901209, 2158.881261530615, 2318.0959425823944, 2357.174988753931, 2382.0828980842416, 2458.188797696756, 2478.529079474894, 2504.887280072401, 2506.5362907862013, 2507.523570521604, 2509.4353770401603, 2421.806255499556, 2482.4786794773117, 2483.044028049196, 2485.2171565956755, 2488.776010159085, 2158.179891641174, 2398.940026499334, 2467.4324905271797, 2467.938301542458, 2468.3462080405993, 2167.2306331252335, 2397.712274527068, 2469.1358024691394, 2486.639437281602, 2487.3657217409627, 8646.956765216197, 8721.21509064181, 8743.300535957109, 8745.606447709868, 8753.08092650269, 5218.921716174261, 5231.2937753403185, 5415.377231618375, 5420.322459692538, 5434.538064264542, 3328.933421331584, 3356.5636467259346, 3392.4677898909813, 3428.0915557377316, 3439.501272315525, 937.6952490440634, 955.5795450001976, 956.0344052506948, 969.3699095587924, 974.4056125763278, 10188.54151563174, 12093.87906120945, 12107.213031153035, 12123.030157587373, 12133.514659413613, 4761.571458568746, 4883.4139903211135, 4943.036107958914, 4951.133551628451, 4987.226979818134, 10065.245106617032, 10186.541605627765, 10199.235057370726, 10200.642977495752, 10205.74485637865, 5471.206675032098, 5479.835604931858, 5484.533814625722, 5486.615936884459, 5494.917576236279, 4475.820967161277, 4661.836835710802, 4842.273658951185, 4948.975255123702, 4957.330760347307, 3428.3143371325837, 3857.9807100964317, 3977.2642061218767, 4011.5787842276723, 4026.4966879140106, 1326.2042774335514, 1351.2770667692737, 1352.377906163176, 1368.5962641020922, 1368.9041767076408, 824.5258976088772, 868.2489646455771, 892.3530440430636, 911.9544022798833, 951.6241084771476, 5590.636608620427, 5669.971650141723, 5843.94156058434, 5887.499562537232, 5966.653710846081, 917.3074328881785, 922.1285884838898, 959.5490119643756, 969.5685419988038, 1014.974625634365, 3272.5582046423906, 3800.524934383202, 3890.3386424307937, 3939.428782826474, 3970.722049456568, 9213.0, 10475.0, 10817.729556761144, 11017.779644407066, 11052.944735276433, 10669.09312708413, 10745.78508429827, 10813.243072984815, 10876.347419154717, 10877.347359158475, 8210.794730131674, 8355.749327520183, 8466.449680770607, 8482.0, 8496.957515212385, 5739.339975902658, 5761.596688231783, 5790.507806836466, 5815.79644712433, 5996.0, 10056.044675755791, 10132.0, 10307.896921030693, 10327.277090603886, 10423.583056677951, 10191.439470829175, 10449.9477502612, 10516.158707303244, 10531.94734026325, 10721.8556288741, 5797.507211886868, 6163.3836616338, 7075.363217310467, 7121.67952442131, 7322.707091716485, 8287.419880608299, 8319.625616847135, 8422.24199822019, 8432.957835210786, 8444.408891377545, 7289.635518224173, 7369.926300736924, 7379.3358597726465, 7505.88741168872, 7507.737229197171, 7246.6376681166785, 7280.599567023864, 7284.053073100675, 7365.926340736738, 7416.629168541659, 6876.449884009367, 6939.063226464363, 6975.162980442379, 6981.720731170696, 6986.371226589631, 4821.5178482152905, 4847.321375007572, 4887.34020907173, 4980.103581355246, 5105.948940510547, 4864.197407427876, 4891.364122664031, 4973.950260497349, 5046.268291097844, 5176.585873130216], "value": 4598.18190522506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.722530244453, 306.77452072726555, 316.81782974789496, 325.4418671977558, 326.0983380951668, 4387.7147985380925, 4655.743934083628, 5171.663841850277, 5336.759845806938, 5391.223519887261, 4694.835680751175, 4793.856184314467, 5307.708076055816, 5319.840404787863, 5389.78440862365, 3925.900747790623, 4142.2958097123565, 4158.833646654138, 4175.31107367284, 4188.560201178878, 3929.4105884117325, 3943.2507823513442, 4137.213929353428, 4179.749215047092, 4209.557996410379, 3463.3765922134007, 3789.1663833956486, 3968.325384684596, 4118.608732170454, 4121.690873184523, 4097.303458412077, 4109.712320137592, 4122.876313710579, 4124.835006599732, 4156.2726522858375, 3622.2936527377174, 3634.854605815791, 3635.4910312556262, 3647.963520364789, 3652.470391793175, 4066.9593304066875, 4743.857684269477, 5066.518680725325, 5152.974235128838, 5258.237555554461, 4668.976655116703, 4717.386739723848, 4723.858284251478, 4741.928871066937, 4773.162332501977, 5510.194103554483, 5539.944600554023, 5637.802676906289, 5637.943620563783, 5641.73061061258, 3783.1651648950237, 3865.903352416184, 3997.0, 3999.2601368746873, 4028.8992775180855, 5193.039287731782, 5637.46444087811, 5638.407967163471, 5830.300363956268, 6007.519398448114, 3390.6609339065894, 3828.8085595720104, 3864.652181303696, 3918.647321741057, 3932.901677458029, 3625.3353784968026, 3908.928268017955, 4095.795210239476, 4109.794510274474, 4117.279476091732, 3975.542812576533, 4599.402077729874, 4710.36410084641, 4719.056188762262, 4726.503717109723, 4784.349399457182, 5388.73056347189, 5402.621816472888, 5742.511886489611, 5744.827655170351, 1493.0, 1516.673915108257, 1518.764591488321, 1520.7946927164912, 1528.709545186411, 994.8213412626325, 1025.369397820335, 1032.5766435761452, 1035.3529044347283, 1044.2794471814575, 5372.167314066326, 5790.768369265266, 5808.912866307009, 5825.825225243249, 5833.620814647034, 6083.178770865877, 6139.0, 6320.652364119926, 6375.6174629522375, 6411.230652321751, 4783.210770222874, 5184.637075404761, 5292.311999440049, 5528.253685752372, 5801.274840644919, 3398.5072164536004, 3400.4219282721997, 3413.385590593717, 3421.203867969336, 3434.4333185024193, 6536.227270918667, 6807.141000779685, 6808.761693340709, 6832.282610325844, 6834.6582670866255, 6085.695715214221, 6436.613803171825, 6674.599524028574, 6681.7327306908155, 6682.264950855402, 6175.84560385994, 6431.234569135317, 6587.374199451045, 6760.560563563353, 6823.522353435212, 4976.328195693535, 6072.0, 6385.8722825544155, 6435.4529864962005, 6490.3185165558825, 5029.893423446836, 5620.437956204347, 6370.77702280418, 6381.266154392119, 6428.453581445629, 5396.83809485716, 6041.942660034564, 6344.397282258178, 6612.702428390638, 6794.320567943166, 3940.763554186758, 4162.646175075152, 4176.791160442027, 4205.705600607928, 4208.431861698754, 5082.669626474268, 5409.864753381197, 5841.591088623925, 6216.53375996793, 6405.391487808651, 5563.360213575491, 5616.550675945996, 5845.766169353178, 6048.562255653427, 6171.117809068277, 5226.738663066755, 6192.876142477216, 6234.625922444487, 6243.531735119975, 6265.874682506415, 5898.233229680114, 6094.695265236809, 6116.66358350295, 6122.387761223842, 6151.876962460815, 4897.114610874768, 5192.818251361185, 6203.0, 6243.8126856194385, 6295.5907865988565, 5110.824510362711, 5236.250968241704, 6068.302145253352, 6074.149619053248, 6117.204763380732], "value": 4344.086936075927, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.9480636355454, 742.676935533043, 749.6326799868062, 755.9168491465941, 794.2732399854132, 14408.71182576348, 14485.710285794277, 14972.475963341285, 14997.700045999074, 15096.622584435394, 11683.707907302321, 13956.2324072176, 13968.790468142986, 13975.580732578013, 13995.580132596038, 721.2318880392392, 916.7478943290598, 918.7060140754961, 929.1173385283973, 929.1962452478625, 718.6406796601699, 809.5345176523493, 895.3463971300954, 937.1612406895824, 939.3096074385321, 917.4403613795579, 955.7323949294208, 966.9613215471408, 967.56459593183, 968.1528662420383, 753.4010461682975, 912.2519533982137, 942.0955882352949, 959.9280053995943, 965.4110992294694, 716.9354758071798, 725.166059032112, 728.4536597551836, 728.6720975560966, 728.9708411663527, 14823.629409264748, 14952.925235373863, 14954.476593319181, 14955.252237388197, 14958.252087395587, 12560.43478043481, 12680.68298292541, 12781.680457988532, 12964.740705185937, 13001.479940802357, 9974.451405172713, 10088.445135517544, 10159.746006349826, 10182.014507171105, 10850.620228291971, 3457.2739724657854, 3504.8072356020407, 3535.734819888519, 3540.805255710935, 3616.150204701893, 79264.0, 80208.0, 80704.0, 80893.59553202255, 81242.9999999994, 9299.900695134062, 9562.808743825084, 9736.269779766546, 9802.117809397188, 9874.456904870158, 18484.44546663602, 22108.668369974464, 22209.55580888373, 22286.88856555707, 22844.97197626111, 7480.214577469287, 7514.736984205636, 7548.66031028605, 7611.543307401574, 7762.262585054411, 3980.800959951991, 4021.746706628589, 4053.2704113259897, 4111.1366613011005, 4329.523752387235, 3400.6089299730847, 3486.877959271413, 3493.5633045869267, 3498.792916443823, 3499.492573576868, 594.1800315564493, 604.4982664388547, 607.8814631146884, 613.8649497110629, 625.1435533319291, 536.4153073150285, 563.4956713741178, 598.9460948514654, 607.6657838188978, 609.6220343387129, 6677.224458995892, 8169.550674712829, 8261.297789687824, 8276.544790036485, 8376.623051962677, 568.7799669708452, 587.2365924298481, 594.9583529152918, 609.7591451376682, 614.2935624032309, 3198.6321573018936, 3258.283177700902, 3840.0783811885503, 3879.8254078566533, 4010.136706956108, 93614.0, 93757.12485750247, 94839.52580237055, 99779.0, 99860.5006974975, 59653.0, 76551.61724191344, 76611.0, 77725.0, 78063.60968195123, 68080.0, 69211.0, 69584.60830783357, 69761.3023869765, 70360.64819675869, 8007.879881801894, 8127.471714338549, 8209.343252539664, 8216.46592971455, 8231.0, 41875.115619796576, 43734.56265437305, 44030.779846100566, 44360.0, 44472.33291500695, 55030.44969550253, 62203.377966219756, 63108.0, 65843.37861417668, 68144.0, 8913.0, 10665.253432259668, 10713.250072494851, 11189.720256993314, 11493.252938558968, 11696.528416291909, 11713.531458741554, 11814.645560633195, 11829.822552661544, 12394.132410731163, 9955.701328960144, 11315.151363647594, 11590.0, 11826.586069487526, 11861.940690296493, 9866.703998880044, 11538.134639901873, 11711.531538738354, 11731.706707332385, 11834.171607987359, 11169.050630696482, 11183.193394280328, 11286.548538058385, 11528.942355288169, 11828.75253701794, 9586.137247647466, 9661.372010819274, 9775.413475191512, 9782.510874456106, 10089.041541053588, 8496.872546911925, 9599.664011759554, 9608.519574021411, 9696.85454718194, 9795.363301385387], "value": 6821.984473582385, "unit": "score"}], "name": "dromaeo_css", "value": 7152.413170636589, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 109.0, 112.0, 111.0, 113.0, 109.0, 112.0, 107.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 149.0, 173.0, 168.0, 120.0, 144.0, 140.0, 118.0, 170.0, 120.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 190.0, 183.0, 184.0, 194.0, 221.0, 185.0, 184.0, 181.0, 184.0], "value": 189.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 74.0, 149.0, 56.0, 57.0, 58.0, 64.0, 128.0, 55.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 134.0, 77.0, 81.0, 84.0, 122.0, 80.0, 77.0, 130.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 125.0, 89.0, 87.0, 88.0, 86.0, 90.0, 88.0, 88.0, 83.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 92.0, 109.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 87.0, 84.0, 84.0, 86.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 58.0, 59.0, 60.0, 59.0, 57.0, 77.0, 60.0, 59.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 69.0, 66.0, 77.0, 71.0, 71.0, 67.0, 67.0, 67.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 96.0, 81.0, 82.0, 68.0, 96.0, 106.0, 105.0, 106.0, 82.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 129.0, 157.0, 156.0, 155.0, 140.0, 137.0, 179.0, 144.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 195.0, 200.0, 196.0, 205.0, 197.0, 200.0, 199.0, 199.0, 195.0], "value": 198.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 70.0, 70.0, 70.0, 71.0, 70.0, 69.0, 69.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1529.4999999999998, "alertThreshold": 2.0}]} 09:57:42 INFO - Return code: 0 09:57:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:57:43 ERROR - # TBPL SUCCESS # 09:57:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:43 INFO - 2016-04-27 09:57:43.041 screenresolution[1683:10280] starting screenresolution argv=screenresolution get 09:57:43 INFO - 2016-04-27 09:57:43.044 screenresolution[1683:10280] Display 0: 1600x1200x32@60 09:57:43 INFO - 2016-04-27 09:57:43.050 screenresolution[1684:10283] starting screenresolution argv=screenresolution list 09:57:43 INFO - Available Modes on Display 0 09:57:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:43 INFO - Intel Iris: 09:57:43 INFO - Chipset Model: Intel Iris 09:57:43 INFO - Type: GPU 09:57:43 INFO - Bus: Built-In 09:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:43 INFO - Vendor: Intel (0x8086) 09:57:43 INFO - Device ID: 0x0a2e 09:57:43 INFO - Revision ID: 0x0009 09:57:43 INFO - Displays: 09:57:43 INFO - Display: 09:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:43 INFO - Display Serial Number: 1600x1200 60 09:57:43 INFO - Main Display: Yes 09:57:43 INFO - Mirror: Off 09:57:43 INFO - Online: Yes 09:57:43 INFO - Rotation: Supported 09:57:43 INFO - Return code: 0 09:57:43 INFO - Running post-action listener: _resource_record_post_action 09:57:43 INFO - Running post-run listener: _resource_record_post_run 09:57:44 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 32480768; Write bytes: 695030272; Read time: 364; Write time: 1889 09:57:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396464640; Write bytes: 398009856; Read time: 29910; Write time: 791 09:57:44 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30699008; Write bytes: 296172544; Read time: 346; Write time: 1095 09:57:44 INFO - Running post-run listener: _upload_blobber_files 09:57:44 INFO - Blob upload gear active. 09:57:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:44 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 09:57:44 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'] 09:57:44 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 09:57:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:45 INFO - (blobuploader) - INFO - Done attempting. 09:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:46 INFO - (blobuploader) - INFO - Done attempting. 09:57:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:46 INFO - Return code: 0 09:57:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7bea9d1db69b6bdd33f0a9ef861633947eac7ed164f8361248a38950d564fe1578656b5fede19bc1b657e16b6c4eae4b3056f85a41697db4edc735e7b4268cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e97d8912a05b82b887cc1afe24b68d322972256ff24bc58b2808a1f9dc4a1380369bece683c571024dc9d4ad5a28981ea8448acbb5aa6fdaffce87a00adeba08"} 09:57:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:46 INFO - Contents: 09:57:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7bea9d1db69b6bdd33f0a9ef861633947eac7ed164f8361248a38950d564fe1578656b5fede19bc1b657e16b6c4eae4b3056f85a41697db4edc735e7b4268cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e97d8912a05b82b887cc1afe24b68d322972256ff24bc58b2808a1f9dc4a1380369bece683c571024dc9d4ad5a28981ea8448acbb5aa6fdaffce87a00adeba08"} 09:57:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:46 INFO - Copying logs to upload dir... 09:57:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.516196 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-27 09:57:47.295202) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 09:57:47.302455) ========= 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.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/7bea9d1db69b6bdd33f0a9ef861633947eac7ed164f8361248a38950d564fe1578656b5fede19bc1b657e16b6c4eae4b3056f85a41697db4edc735e7b4268cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e97d8912a05b82b887cc1afe24b68d322972256ff24bc58b2808a1f9dc4a1380369bece683c571024dc9d4ad5a28981ea8448acbb5aa6fdaffce87a00adeba08"} build_url:https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/C27PGWi9RGKryFtyAbtO0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7bea9d1db69b6bdd33f0a9ef861633947eac7ed164f8361248a38950d564fe1578656b5fede19bc1b657e16b6c4eae4b3056f85a41697db4edc735e7b4268cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e97d8912a05b82b887cc1afe24b68d322972256ff24bc58b2808a1f9dc4a1380369bece683c571024dc9d4ad5a28981ea8448acbb5aa6fdaffce87a00adeba08"}' ========= master_lag: 0.49 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 09:57:47.807614) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:57:47.807927) ========= 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.TjOju89l7d/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.8vQP6pqQib/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.004993 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:57:47.827737) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 09:57:47.828000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 09:57:47.828331) ========= ========= Total master_lag: 7.18 =========