builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0137 starttime: 1462874864.46 results: success (0) buildid: 20160510020804 builduid: 64befc246da6415a9dd45bab51ba0d11 revision: b041ad17b6c87ddf64db984292e95a777fb9959f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.459332) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.459798) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.460077) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.479907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.480219) ========= 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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 03:07:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.5M=0.001s 2016-05-10 03:07:44 (13.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116309 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.614577) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.615103) ========= 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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.026725 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.665521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:44.665865) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b041ad17b6c87ddf64db984292e95a777fb9959f --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 b041ad17b6c87ddf64db984292e95a777fb9959f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 03:07:44,739 truncating revision to first 12 chars 2016-05-10 03:07:44,739 Setting DEBUG logging. 2016-05-10 03:07:44,739 attempt 1/10 2016-05-10 03:07:44,740 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b041ad17b6c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 03:07:45,429 unpacking tar archive at: fx-team-b041ad17b6c8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.929514 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:45.612747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:45.613066) ========= script_repo_revision: b041ad17b6c87ddf64db984292e95a777fb9959f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:45.613496) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:45.613758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 03:07:45.627218) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-05-10 03:07:45.627575) ========= /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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:07:45 INFO - MultiFileLogger online at 20160510 03:07:45 in /builds/slave/test 03:07:45 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 03:07:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:07:45 INFO - {'append_to_log': False, 03:07:45 INFO - 'base_work_dir': '/builds/slave/test', 03:07:45 INFO - 'blob_upload_branch': 'Fx-Team', 03:07:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:07:45 INFO - 'branch': 'Fx-Team', 03:07:45 INFO - 'buildbot_json_path': 'buildprops.json', 03:07:45 INFO - 'config_files': ('talos/mac_config.py',), 03:07:45 INFO - 'default_actions': ('clobber', 03:07:45 INFO - 'read-buildbot-config', 03:07:45 INFO - 'download-and-extract', 03:07:45 INFO - 'populate-webroot', 03:07:45 INFO - 'create-virtualenv', 03:07:45 INFO - 'install', 03:07:45 INFO - 'run-tests'), 03:07:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:07:45 INFO - 'download_minidump_stackwalk': True, 03:07:45 INFO - 'download_symbols': 'ondemand', 03:07:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:07:45 INFO - 'tooltool.py': '/tools/tooltool.py', 03:07:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:07:45 INFO - '/tools/misc-python/virtualenv.py')}, 03:07:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:07:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:07:45 INFO - 'installer_path': 'installer.exe', 03:07:45 INFO - 'log_level': 'info', 03:07:45 INFO - 'log_name': 'talos', 03:07:45 INFO - 'log_to_console': True, 03:07:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:07:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:07:45 INFO - 'opt_config_files': (), 03:07:45 INFO - 'pip_index': False, 03:07:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:07:45 INFO - 'cmd': ('bash', 03:07:45 INFO - '-c', 03:07:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:07:45 INFO - 'enabled': True, 03:07:45 INFO - 'halt_on_failure': False, 03:07:45 INFO - 'name': 'check_screen_resolution'},), 03:07:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:07:45 INFO - 'cmd': ('bash', 03:07:45 INFO - '-c', 03:07:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:07:45 INFO - 'enabled': True, 03:07:45 INFO - 'halt_on_failure': False, 03:07:45 INFO - 'name': 'check_screen_resolution'},), 03:07:45 INFO - 'run_cmd_checks_enabled': True, 03:07:45 INFO - 'sps_profile': False, 03:07:45 INFO - 'sps_profile_interval': 0, 03:07:45 INFO - 'suite': 'dromaeojs', 03:07:45 INFO - 'system_bits': '32', 03:07:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:07:45 INFO - 'title': 't-yosemite-r7-0137', 03:07:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:07:45 INFO - 'use_talos_json': True, 03:07:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:07:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:07:45 INFO - 'work_dir': 'build'} 03:07:45 INFO - ##### 03:07:45 INFO - ##### Running clobber step. 03:07:45 INFO - ##### 03:07:45 INFO - Running pre-action listener: _resource_record_pre_action 03:07:45 INFO - Running main action method: clobber 03:07:45 INFO - rmtree: /builds/slave/test/build 03:07:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:07:47 INFO - Running post-action listener: _resource_record_post_action 03:07:47 INFO - ##### 03:07:47 INFO - ##### Finished clobber step (success) 03:07:47 INFO - ##### 03:07:47 INFO - ##### 03:07:47 INFO - ##### Running read-buildbot-config step. 03:07:47 INFO - ##### 03:07:47 INFO - Running pre-action listener: _resource_record_pre_action 03:07:47 INFO - Running main action method: read_buildbot_config 03:07:47 INFO - Using buildbot properties: 03:07:47 INFO - { 03:07:47 INFO - "project": "", 03:07:47 INFO - "product": "firefox", 03:07:47 INFO - "script_repo_revision": "production", 03:07:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:07:47 INFO - "repository": "", 03:07:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:07:47 INFO - "buildid": "20160510020804", 03:07:47 INFO - "pgo_build": "False", 03:07:47 INFO - "basedir": "/builds/slave/test", 03:07:47 INFO - "buildnumber": 5, 03:07:47 INFO - "slavename": "t-yosemite-r7-0137", 03:07:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:07:47 INFO - "platform": "yosemite_r7", 03:07:47 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:07:47 INFO - "branch": "fx-team", 03:07:47 INFO - "repo_path": "integration/fx-team", 03:07:47 INFO - "revision": "b041ad17b6c87ddf64db984292e95a777fb9959f", 03:07:47 INFO - "stage_platform": "macosx64", 03:07:47 INFO - "builduid": "64befc246da6415a9dd45bab51ba0d11", 03:07:47 INFO - "slavebuilddir": "test" 03:07:47 INFO - } 03:07:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 03:07:47 INFO - Running post-action listener: _resource_record_post_action 03:07:47 INFO - ##### 03:07:47 INFO - ##### Finished read-buildbot-config step (success) 03:07:47 INFO - ##### 03:07:47 INFO - ##### 03:07:47 INFO - ##### Running download-and-extract step. 03:07:47 INFO - ##### 03:07:47 INFO - Running pre-action listener: _resource_record_pre_action 03:07:47 INFO - Running main action method: download_and_extract 03:07:47 INFO - mkdir: /builds/slave/test/build/tests 03:07:47 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')]} 03:07:47 INFO - https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:07:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 03:07:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 03:07:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:48 INFO - Downloaded 1199 bytes. 03:07:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 03:07:48 INFO - Using the following test package requirements: 03:07:48 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 03:07:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 03:07:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 03:07:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'jsshell-mac.zip'], 03:07:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 03:07:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 03:07:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 03:07:48 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 03:07:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 03:07:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:07:48 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 03:07:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:48 INFO - https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:07:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:07:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:07:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:49 INFO - Downloaded 18146552 bytes. 03:07: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'] 03:07: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 03:07:53 INFO - Return code: 0 03:07:53 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 03:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:53 INFO - https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:07:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:07:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:07:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:55 INFO - Downloaded 18146552 bytes. 03:07:55 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'] 03:07:55 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 03:07:58 INFO - Return code: 0 03:07:58 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')]} 03:07:58 INFO - https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:07:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 03:07:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 03:07:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:08:00 INFO - Downloaded 10840152 bytes. 03:08:00 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'] 03:08:00 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 03:08:00 INFO - Return code: 0 03:08:00 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')]} 03:08:00 INFO - https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 03:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c5H2mfLcTk6nwJpcGooKBA/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 03:08:05 INFO - Downloaded 110416798 bytes. 03:08:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:08:05 INFO - mkdir: /builds/slave/test/properties 03:08:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:08:05 INFO - Writing to file /builds/slave/test/properties/build_url 03:08:05 INFO - Contents: 03:08:05 INFO - build_url:https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:08:06 INFO - Running post-action listener: _resource_record_post_action 03:08:06 INFO - Running post-action listener: set_extra_try_arguments 03:08:06 INFO - ##### 03:08:06 INFO - ##### Finished download-and-extract step (success) 03:08:06 INFO - ##### 03:08:06 INFO - ##### 03:08:06 INFO - ##### Running populate-webroot step. 03:08:06 INFO - ##### 03:08:06 INFO - Running pre-action listener: _resource_record_pre_action 03:08:06 INFO - Running main action method: populate_webroot 03:08:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:08:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:08:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:08:06 INFO - '--tppagecycles', 03:08:06 INFO - '7'], 03:08:06 INFO - 'tests': ['tsvgm']}}, 03:08:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:08:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:08:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:08:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:08:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['damp', 'tps']}, 03:08:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'tests': ['damp', 'tps']}, 03:08:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['dromaeo_dom']}, 03:08:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:08:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'other-e10s': {'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['a11yr', 03:08:06 INFO - 'ts_paint', 03:08:06 INFO - 'tpaint', 03:08:06 INFO - 'sessionrestore', 03:08:06 INFO - 'sessionrestore_no_auto_restore', 03:08:06 INFO - 'tabpaint']}, 03:08:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['tsvgx', 03:08:06 INFO - 'tsvgr_opacity', 03:08:06 INFO - 'tart', 03:08:06 INFO - 'tscrollx', 03:08:06 INFO - 'cart']}, 03:08:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:08:06 INFO - 'tsvgr_opacity', 03:08:06 INFO - 'tart', 03:08:06 INFO - 'tscrollx', 03:08:06 INFO - 'cart']}, 03:08:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'talos_options': ['--disable-e10s'], 03:08:06 INFO - 'tests': ['tp5o']}, 03:08:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'tests': ['tp5o']}, 03:08:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'talos_options': ['--disable-e10s', 03:08:06 INFO - '--xperf_path', 03:08:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:06 INFO - 'tests': ['tp5n']}, 03:08:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:06 INFO - 'talos_options': ['--xperf_path', 03:08:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:06 INFO - 'tests': ['tp5n']}}, 03:08:06 INFO - 'talos.zip': {'path': '', 03:08:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:08:06 INFO - Running post-action listener: _resource_record_post_action 03:08:06 INFO - ##### 03:08:06 INFO - ##### Finished populate-webroot step (success) 03:08:06 INFO - ##### 03:08:06 INFO - ##### 03:08:06 INFO - ##### Running create-virtualenv step. 03:08:06 INFO - ##### 03:08:06 INFO - Running pre-action listener: _resource_record_pre_action 03:08:06 INFO - Running main action method: create_virtualenv 03:08:06 INFO - Creating virtualenv /builds/slave/test/build/venv 03:08:06 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 03:08:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:08:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:08:06 INFO - Using real prefix '/tools/python27' 03:08:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:08:07 INFO - Installing distribute.............................................................................................................................................................................................done. 03:08:10 INFO - Installing pip.................done. 03:08:10 INFO - Return code: 0 03:08:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:08: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')]} 03:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08: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')]} 03:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08: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', 'pip>=1.5'] in /builds/slave/test/build 03:08: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 pip>=1.5 03:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:10 INFO - 'HOME': '/Users/cltbld', 03:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:10 INFO - 'LOGNAME': 'cltbld', 03:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:10 INFO - 'MOZ_NO_REMOTE': '1', 03:08:10 INFO - 'NO_EM_RESTART': '1', 03:08:10 INFO - 'PAGER': '/bin/cat', 03:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:10 INFO - 'PWD': '/builds/slave/test', 03:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:10 INFO - 'SHELL': '/bin/bash', 03:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:10 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:10 INFO - 'USER': 'cltbld', 03:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:10 INFO - 'XPC_FLAGS': '0x0', 03:08:10 INFO - 'XPC_SERVICE_NAME': '0', 03:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:10 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 03:08:10 INFO - Cleaning up... 03:08:10 INFO - Return code: 0 03:08:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:08: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')]} 03:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08: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')]} 03:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08: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', '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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08: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', 'psutil>=0.7.1'] in /builds/slave/test/build 03:08: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 psutil>=0.7.1 03:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:10 INFO - 'HOME': '/Users/cltbld', 03:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:10 INFO - 'LOGNAME': 'cltbld', 03:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:10 INFO - 'MOZ_NO_REMOTE': '1', 03:08:10 INFO - 'NO_EM_RESTART': '1', 03:08:10 INFO - 'PAGER': '/bin/cat', 03:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:10 INFO - 'PWD': '/builds/slave/test', 03:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:10 INFO - 'SHELL': '/bin/bash', 03:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:10 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:10 INFO - 'USER': 'cltbld', 03:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:10 INFO - 'XPC_FLAGS': '0x0', 03:08:10 INFO - 'XPC_SERVICE_NAME': '0', 03:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:10 INFO - Downloading/unpacking psutil>=0.7.1 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:08:16 INFO - Installing collected packages: psutil 03:08:16 INFO - Running setup.py install for psutil 03:08:16 INFO - building 'psutil._psutil_osx' extension 03:08:16 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 03:08:17 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 03:08:17 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 03:08:17 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 03:08:17 INFO - building 'psutil._psutil_posix' extension 03:08:17 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 03:08:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:08:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:08:17 INFO - ^ 03:08:17 INFO - 1 warning generated. 03:08:17 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 03:08:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:08:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:08:17 INFO - Successfully installed psutil 03:08:17 INFO - Cleaning up... 03:08:17 INFO - Return code: 0 03:08:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:08:17 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')]} 03:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:17 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')]} 03:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:17 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:17 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 03:08:17 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 03:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:17 INFO - 'HOME': '/Users/cltbld', 03:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:17 INFO - 'LOGNAME': 'cltbld', 03:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:17 INFO - 'MOZ_NO_REMOTE': '1', 03:08:17 INFO - 'NO_EM_RESTART': '1', 03:08:17 INFO - 'PAGER': '/bin/cat', 03:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:17 INFO - 'PWD': '/builds/slave/test', 03:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:17 INFO - 'SHELL': '/bin/bash', 03:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:17 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:17 INFO - 'USER': 'cltbld', 03:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:17 INFO - 'XPC_FLAGS': '0x0', 03:08:17 INFO - 'XPC_SERVICE_NAME': '0', 03:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:08:17 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 03:08:17 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 03:08:17 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 03:08:17 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 03:08:17 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 03:08:17 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 03:08:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:08:26 INFO - Installing collected packages: mozsystemmonitor 03:08:26 INFO - Running setup.py install for mozsystemmonitor 03:08:27 INFO - Successfully installed mozsystemmonitor 03:08:27 INFO - Cleaning up... 03:08:27 INFO - Return code: 0 03:08:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:27 INFO - 'HOME': '/Users/cltbld', 03:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:27 INFO - 'LOGNAME': 'cltbld', 03:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:27 INFO - 'MOZ_NO_REMOTE': '1', 03:08:27 INFO - 'NO_EM_RESTART': '1', 03:08:27 INFO - 'PAGER': '/bin/cat', 03:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:27 INFO - 'PWD': '/builds/slave/test', 03:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:27 INFO - 'SHELL': '/bin/bash', 03:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:27 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:27 INFO - 'USER': 'cltbld', 03:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:27 INFO - 'XPC_FLAGS': '0x0', 03:08:27 INFO - 'XPC_SERVICE_NAME': '0', 03:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08:30 INFO - Downloading blobuploader-1.2.4.tar.gz 03:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:08:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:08:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:31 INFO - Downloading docopt-0.6.1.tar.gz 03:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:08:31 INFO - Installing collected packages: blobuploader, requests, docopt 03:08:31 INFO - Running setup.py install for blobuploader 03:08:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:08:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:08:31 INFO - Running setup.py install for requests 03:08:32 INFO - Running setup.py install for docopt 03:08:32 INFO - Successfully installed blobuploader requests docopt 03:08:32 INFO - Cleaning up... 03:08:32 INFO - Return code: 0 03:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:32 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:32 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 03:08:32 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 03:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:32 INFO - 'HOME': '/Users/cltbld', 03:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:32 INFO - 'LOGNAME': 'cltbld', 03:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:32 INFO - 'MOZ_NO_REMOTE': '1', 03:08:32 INFO - 'NO_EM_RESTART': '1', 03:08:32 INFO - 'PAGER': '/bin/cat', 03:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:32 INFO - 'PWD': '/builds/slave/test', 03:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:32 INFO - 'SHELL': '/bin/bash', 03:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:32 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:32 INFO - 'USER': 'cltbld', 03:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:32 INFO - 'XPC_FLAGS': '0x0', 03:08:32 INFO - 'XPC_SERVICE_NAME': '0', 03:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:08:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-sAm0Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:08:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-W7k3eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:08:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ne_lAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-7Whh85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j0pJEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-fIQb3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-XT_HIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-5VPXkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OqfF90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qssWzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-W8tjLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rBridY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:08:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j04Ye6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:08:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OpNL0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-4I6Mlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:08:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-8KoxPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:08:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-CT6ab4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:08:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:08:34 INFO - Running setup.py install for manifestparser 03:08:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:08:34 INFO - Running setup.py install for mozcrash 03:08:34 INFO - Running setup.py install for mozdebug 03:08:34 INFO - Running setup.py install for mozdevice 03:08:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Running setup.py install for mozfile 03:08:35 INFO - Running setup.py install for mozhttpd 03:08:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Running setup.py install for mozinfo 03:08:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Running setup.py install for mozInstall 03:08:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Running setup.py install for mozleak 03:08:35 INFO - Running setup.py install for mozlog 03:08:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:08:35 INFO - Running setup.py install for moznetwork 03:08:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:08:36 INFO - Running setup.py install for mozprocess 03:08:36 INFO - Running setup.py install for mozprofile 03:08:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:08:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:08:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:08:36 INFO - Running setup.py install for mozrunner 03:08:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:08:36 INFO - Running setup.py install for mozscreenshot 03:08:36 INFO - Running setup.py install for moztest 03:08:36 INFO - Running setup.py install for mozversion 03:08:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:08:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:08:37 INFO - Cleaning up... 03:08:37 INFO - Return code: 0 03:08:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:37 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:37 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 03:08:37 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 03:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:37 INFO - 'HOME': '/Users/cltbld', 03:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:37 INFO - 'LOGNAME': 'cltbld', 03:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:37 INFO - 'MOZ_NO_REMOTE': '1', 03:08:37 INFO - 'NO_EM_RESTART': '1', 03:08:37 INFO - 'PAGER': '/bin/cat', 03:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:37 INFO - 'PWD': '/builds/slave/test', 03:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:37 INFO - 'SHELL': '/bin/bash', 03:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:37 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:37 INFO - 'USER': 'cltbld', 03:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:37 INFO - 'XPC_FLAGS': '0x0', 03:08:37 INFO - 'XPC_SERVICE_NAME': '0', 03:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9ajPWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KaWGY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-q4PsDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-twBfit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ct2ZSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-5_ar7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:08:37 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)) 03:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:08:37 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-1KnAos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-oEsFfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-liX_mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KD7Eqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KctqH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j9INCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-x3ghdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-0ZzTMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-tkADuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-WuNPY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:08:38 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)) 03:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:08:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-PSkHMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:08:39 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)) 03:08:39 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)) 03:08:39 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)) 03:08:39 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)) 03:08:39 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)) 03:08:39 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)) 03:08:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:43 INFO - Downloading blessings-1.6.tar.gz 03:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:08:43 INFO - Installing collected packages: blessings 03:08:43 INFO - Running setup.py install for blessings 03:08:43 INFO - Successfully installed blessings 03:08:43 INFO - Cleaning up... 03:08:43 INFO - Return code: 0 03:08:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:08:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:44 INFO - Reading from file tmpfile_stdout 03:08:44 INFO - Current package versions: 03:08:44 INFO - blessings == 1.6 03:08:44 INFO - blobuploader == 1.2.4 03:08:44 INFO - docopt == 0.6.1 03:08:44 INFO - manifestparser == 1.1 03:08:44 INFO - mozInstall == 1.12 03:08:44 INFO - mozcrash == 0.17 03:08:44 INFO - mozdebug == 0.1 03:08:44 INFO - mozdevice == 0.48 03:08:44 INFO - mozfile == 1.2 03:08:44 INFO - mozhttpd == 0.7 03:08:44 INFO - mozinfo == 0.9 03:08:44 INFO - mozleak == 0.1 03:08:44 INFO - mozlog == 3.1 03:08:44 INFO - moznetwork == 0.27 03:08:44 INFO - mozprocess == 0.22 03:08:44 INFO - mozprofile == 0.28 03:08:44 INFO - mozrunner == 6.11 03:08:44 INFO - mozscreenshot == 0.1 03:08:44 INFO - mozsystemmonitor == 0.0 03:08:44 INFO - moztest == 0.7 03:08:44 INFO - mozversion == 1.4 03:08:44 INFO - psutil == 3.1.1 03:08:44 INFO - requests == 1.2.3 03:08:44 INFO - wsgiref == 0.1.2 03:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:44 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:44 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 03:08:44 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 03:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:44 INFO - 'HOME': '/Users/cltbld', 03:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:44 INFO - 'LOGNAME': 'cltbld', 03:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:44 INFO - 'MOZ_NO_REMOTE': '1', 03:08:44 INFO - 'NO_EM_RESTART': '1', 03:08:44 INFO - 'PAGER': '/bin/cat', 03:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:44 INFO - 'PWD': '/builds/slave/test', 03:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:44 INFO - 'SHELL': '/bin/bash', 03:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:44 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:44 INFO - 'USER': 'cltbld', 03:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:44 INFO - 'XPC_FLAGS': '0x0', 03:08:44 INFO - 'XPC_SERVICE_NAME': '0', 03:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 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)) 03:08:45 INFO - Cleaning up... 03:08:45 INFO - Return code: 0 03:08:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:45 INFO - 'HOME': '/Users/cltbld', 03:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:45 INFO - 'LOGNAME': 'cltbld', 03:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:45 INFO - 'MOZ_NO_REMOTE': '1', 03:08:45 INFO - 'NO_EM_RESTART': '1', 03:08:45 INFO - 'PAGER': '/bin/cat', 03:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:45 INFO - 'PWD': '/builds/slave/test', 03:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:45 INFO - 'SHELL': '/bin/bash', 03:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:45 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:45 INFO - 'USER': 'cltbld', 03:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:45 INFO - 'XPC_FLAGS': '0x0', 03:08:45 INFO - 'XPC_SERVICE_NAME': '0', 03:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:45 INFO - Downloading/unpacking jsonschema 03:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:08:51 INFO - Downloading/unpacking functools32 (from jsonschema) 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:51 INFO - Downloading functools32-3.2.3-2.tar.gz 03:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:08:51 INFO - warning: no files found matching '*.txt' 03:08:51 INFO - no previously-included directories found matching 'build' 03:08:51 INFO - no previously-included directories found matching 'dist' 03:08:51 INFO - no previously-included directories found matching '.git*' 03:08:51 INFO - Installing collected packages: jsonschema, functools32 03:08:51 INFO - Running setup.py install for functools32 03:08:51 INFO - warning: no files found matching '*.txt' 03:08:51 INFO - no previously-included directories found matching 'build' 03:08:51 INFO - no previously-included directories found matching 'dist' 03:08:51 INFO - no previously-included directories found matching '.git*' 03:08:51 INFO - Successfully installed jsonschema functools32 03:08:51 INFO - Cleaning up... 03:08:51 INFO - Return code: 0 03:08:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:08:51 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')]} 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 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')]} 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe18d9cf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 03:08:51 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 03:08:51 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 03:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:08:51 INFO - 'HOME': '/Users/cltbld', 03:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:08:51 INFO - 'LOGNAME': 'cltbld', 03:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:51 INFO - 'MOZ_NO_REMOTE': '1', 03:08:51 INFO - 'NO_EM_RESTART': '1', 03:08:51 INFO - 'PAGER': '/bin/cat', 03:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:51 INFO - 'PWD': '/builds/slave/test', 03:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:08:51 INFO - 'SHELL': '/bin/bash', 03:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:08:51 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:08:51 INFO - 'USER': 'cltbld', 03:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:51 INFO - 'XPC_FLAGS': '0x0', 03:08:51 INFO - 'XPC_SERVICE_NAME': '0', 03:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:52 INFO - Downloading/unpacking flake8 03:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:08:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:08:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:08:57 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 03:08:57 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 03:08:57 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 03:08:57 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 03:08:57 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 03:08:57 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 03:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:08:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:08:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:08:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:08:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:08:57 INFO - no previously-included directories found matching 'docs/_build' 03:08:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:08:57 INFO - Running setup.py install for pep8 03:08:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:08:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:08:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:08:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:08:57 INFO - no previously-included directories found matching 'docs/_build' 03:08:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:08:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:08:57 INFO - Cleaning up... 03:08:58 INFO - Return code: 0 03:08:58 INFO - Running post-action listener: _resource_record_post_action 03:08:58 INFO - Running post-action listener: _start_resource_monitoring 03:08:58 INFO - Starting resource monitoring. 03:08:58 INFO - ##### 03:08:58 INFO - ##### Finished create-virtualenv step (success) 03:08:58 INFO - ##### 03:08:58 INFO - ##### 03:08:58 INFO - ##### Running install step. 03:08:58 INFO - ##### 03:08:58 INFO - Running pre-action listener: _resource_record_pre_action 03:08:58 INFO - Running main action method: install 03:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:58 INFO - Reading from file tmpfile_stdout 03:08:58 INFO - Detecting whether we're running mozinstall >=1.0... 03:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:08:58 INFO - Reading from file tmpfile_stdout 03:08:58 INFO - Output received: 03:08:58 INFO - Usage: mozinstall [options] installer 03:08:58 INFO - Options: 03:08:58 INFO - -h, --help show this help message and exit 03:08:58 INFO - -d DEST, --destination=DEST 03:08:58 INFO - Directory to install application into. [default: 03:08:58 INFO - "/builds/slave/test"] 03:08:58 INFO - --app=APP Application being installed. [default: firefox] 03:08:58 INFO - mkdir: /builds/slave/test/build/application 03:08:58 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'] 03:08:58 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 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Output received: 03:09:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:09:32 INFO - Running post-action listener: _resource_record_post_action 03:09:32 INFO - ##### 03:09:32 INFO - ##### Finished install step (success) 03:09:32 INFO - ##### 03:09:32 INFO - ##### 03:09:32 INFO - ##### Running run-tests step. 03:09:32 INFO - ##### 03:09:32 INFO - Running pre-action listener: _resource_record_pre_action 03:09:32 INFO - Running main action method: run_tests 03:09:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:09:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:09:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:09:32 INFO - 2016-05-10 03:09:32.785 screenresolution[1643:7557] starting screenresolution argv=screenresolution get 03:09:32 INFO - 2016-05-10 03:09:32.788 screenresolution[1643:7557] Display 0: 1600x1200x32@60 03:09:32 INFO - 2016-05-10 03:09:32.794 screenresolution[1644:7559] starting screenresolution argv=screenresolution list 03:09:32 INFO - Available Modes on Display 0 03:09:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:09:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:09:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:09:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:09:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:09:33 INFO - Intel Iris: 03:09:33 INFO - Chipset Model: Intel Iris 03:09:33 INFO - Type: GPU 03:09:33 INFO - Bus: Built-In 03:09:33 INFO - VRAM (Dynamic, Max): 1536 MB 03:09:33 INFO - Vendor: Intel (0x8086) 03:09:33 INFO - Device ID: 0x0a2e 03:09:33 INFO - Revision ID: 0x0009 03:09:33 INFO - Displays: 03:09:33 INFO - Display: 03:09:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:09:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:09:33 INFO - Display Serial Number: 1600x1200 60 03:09:33 INFO - Main Display: Yes 03:09:33 INFO - Mirror: Off 03:09:33 INFO - Online: Yes 03:09:33 INFO - Rotation: Supported 03:09:33 INFO - Return code: 0 03:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:09:33 INFO - Python 2.7.3 03:09:33 INFO - Return code: 0 03:09:33 INFO - grabbing minidump binary from tooltool 03:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:33 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 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:09:33 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 03:09:33 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 03:09:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:09:33 INFO - Return code: 0 03:09:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:09:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:09:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:09:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:09:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:09:34 INFO - Return code: 0 03:09:34 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/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 03:09:34 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/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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 03:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X8t88Nb2yU/Render', 03:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:34 INFO - 'HOME': '/Users/cltbld', 03:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:34 INFO - 'LOGNAME': 'cltbld', 03:09:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:09:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:34 INFO - 'MOZ_NO_REMOTE': '1', 03:09:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:09:34 INFO - 'NO_EM_RESTART': '1', 03:09:34 INFO - 'PAGER': '/bin/cat', 03:09:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:34 INFO - 'PWD': '/builds/slave/test', 03:09:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:34 INFO - 'SHELL': '/bin/bash', 03:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fT6P1WEhJd/Listeners', 03:09:34 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 03:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:34 INFO - 'USER': 'cltbld', 03:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:34 INFO - 'XPC_FLAGS': '0x0', 03:09:34 INFO - 'XPC_SERVICE_NAME': '0', 03:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:34 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/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 03:09:34 INFO - mozversion application_buildid: 20160510020804 03:09:34 INFO - mozversion application_changeset: b041ad17b6c87ddf64db984292e95a777fb9959f 03:09:34 INFO - mozversion application_display_name: Nightly 03:09:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:09:34 INFO - mozversion application_name: Firefox 03:09:34 INFO - mozversion application_remotingname: firefox 03:09:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:09:34 INFO - mozversion application_vendor: Mozilla 03:09:34 INFO - mozversion application_version: 49.0a1 03:09:34 INFO - mozversion platform_buildid: 20160510020804 03:09:34 INFO - mozversion platform_changeset: b041ad17b6c87ddf64db984292e95a777fb9959f 03:09:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:09:34 INFO - mozversion platform_version: 49.0a1 03:09:34 INFO - using testdate: 1462874974 03:09:34 INFO - actual date: 1462874974 03:09:34 INFO - starting webserver on 'localhost:49275' 03:09:34 INFO - SUITE-START | Running 2 tests 03:09:34 INFO - TEST-START | dromaeo_css 03:09:34 INFO - operating with platform_type : mac_ 03:09:34 INFO - Initialising browser for dromaeo_css test... 03:09:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp03v0Mc/profile http://localhost:49275/getInfo.html) 03:09:36 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 03:09:36 INFO - PROCESS | 1662 | colorDepth:24 03:09:36 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 03:09:36 INFO - PROCESS | 1662 | __metrics 03:09:36 INFO - PROCESS | 1662 | 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" 03:09:36 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:09:37 INFO - TEST-INFO | 1662: exit 0 03:09:37 INFO - Browser initialized. 03:09:37 INFO - Running cycle 1/1 for dromaeo_css test... 03:09:37 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp03v0Mc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:49 INFO - PROCESS | 1664 | RSS: Main: 238874624 03:09:49 INFO - PROCESS | 1664 | 03:10:41 INFO - PROCESS | 1664 | 1462875041334 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] 03:12:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:12:53 INFO - PROCESS | 1664 | RSS: Main: 302391296 03:12:53 INFO - PROCESS | 1664 | 03:15:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:15:57 INFO - PROCESS | 1664 | RSS: Main: 374288384 03:15:57 INFO - PROCESS | 1664 | 03:18:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:19:00 INFO - PROCESS | 1664 | RSS: Main: 368988160 03:19:00 INFO - PROCESS | 1664 | 03:22:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:22:03 INFO - PROCESS | 1664 | RSS: Main: 368726016 03:22:03 INFO - PROCESS | 1664 | 03:25:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:25:07 INFO - PROCESS | 1664 | RSS: Main: 335630336 03:25:07 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:28:10 INFO - PROCESS | 1664 | RSS: Main: 408096768 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | __start_tp_report 03:28:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:28:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:28:10 INFO - PROCESS | 1664 | |i|pagename|runs| 03:28:10 INFO - PROCESS | 1664 | |0;dojo.html;3223.9838800806;3456.6197718251005;3466.4800279958013;3481.5125882376487;3499.7575860569495;5586.469285417878;5604.747786349614;5626.662400255982;5672.829815105543;5697.6866272355;5616.241807356006;5682.659040457565;5682.971585142079;5699.914501282474;5704.743286552105;5175.870603234921;5208.635395522315;5239.973800130994;5242.580593552507;5316;5224.895502089956;5247.973760131213;5265.289185959904;5268.894622107556;5272.024675435056;5149.17613181891;5158.019976204528;5169.991851588941;5172.612054095939;5272.314599102111;5237.109691352479;5286.233496143038;5288.81489147878;5298.761555730001;5333.413324534298;4212.85255016073;5086.669366491166;5089.312942752718;5132.563732082777;5206.661566998133;5523.005858945388;5530.668159910378;5553.750081246313;5572.192032155355;5573;6242.5942313749465;6255.186825712673;6258.34287399821;6273.8745225095245;6279.403456671609;6114.510272098682;6119.41115851624;6134.69326533676;6278.843028924268;6313.43179113882;4705.364775755298;5690.630109042858;5714.942850571482;5737.139429085671;5748.540116790647;6290.654014029273;6294.961331380271;6297.559170858088;6318.905216421757;6330.177076980055;5957.344692083867;5980.611260268069;6058.545609079337;6125.632462052202;6159.938400616026;6274;6280.905786413207;6290.24517057956;6293.244810622663;6360.809175724736;6197.938020619827;6240.157578726905;6250;6263.96868015657;6288.968555157287;6127.18952814863;6153.5692501525355;6167.753289868355;6209.5032397408095;6268.153799237136;4869.586085182799;4948.282499037691;4957.504249575014;4958.305837182797;4974.129527332702;4427.6236519896165;4442.422485076984;4453.3320001999955;4469.709468884577;4491.236489796743;5698.686572238484;5722.198892155102;5726.9713651431475;5727.684977326204;5736.598438109292;6102.337773232528;6161.260648722182;6199.938000620026;6200.503959683215;6221.875562488724;5699.401562835843;5703.800366987136;5704.429557044263;5711.9714401428555;5737.225474560964;5383.596230282745;5401.675899446046;5404.729763511808;5413.918791218135;5424.755885985141;4667.463241727245;5107.693538387634;6270.15352927349;6335.366463353628;6340;6252.437280644582;6265.8120256392385;6285.654289014148;6305.621662700252;6333.20834895638;6317.084022816574;6341.143945567474;6355.3326900674765;6355.459785918249;6376.3623637635865;6007.038873780174;6024.1265016573225;6101.115338275837;6186.536009799193;6203.96898015507;6188.164597779242;6214.098955651496;6220.502359811295;6243.219597550306;6243.406876346739;6116.625929066835;6134.681512609229;6139.032198454696;6142.0997666483;6160.938390616216;6105.793350785129;6117.051856962178;6122.602030867979;6123.557162997564;6126.059675755565;5766.567507437043;5820.679862607597;5849.210356601804;5925.377835327228;5934.495567876779;6071.150038994456;6092.780947655669;6117.571769976059;6124.938750612437;6132.110843927695;5302.8939421212135;5702.403925264482;5760.337561180518;5802.622829516068;5914.201582786269 03:28:10 INFO - PROCESS | 1664 | |1;ext.html;494.1401960588576;526.4682670502791;535.8719894621823;563.4534501533514;572.7909313100714;40475;43050.35424468628;43147.784261078654;43183.136337273274;43227;43568.564314356845;44301.55698443014;44312;44315.33526997089;44378.002989865454;8753.7811554711;8925.41984770991;8962.955185224066;9004.954975225115;9692.466914319728;8811.339149563777;11781.882181178164;12064.396780161002;12069.155159138863;12199.512019519208;11867;11889.405529723523;11897.70255743609;11914.821277680841;11919;8862.069482704288;11892.524299028071;11917.702057448632;11981.580644677395;11982.101342399312;11546.249493782878;11846.407679616072;11878;11881.40592970348;11885.762284754257;40784.59215407867;41260.1747965039;41281.38077928834;41293.38059929074;41369.58630413687;3186.6972637599392;3236.6277878044093;3351.8156501392414;3809.4285857121654;4099.938500922489;29907.112075430054;30519.847400763076;30524.548846151858;30784;30835.074947751604;2711.2815103997455;2758.3931535062256;2783.73554512323;2845.374017716099;2862.8997985070423;483235;483838;485054;485268;485873;63863.36136638667;63967.99999999907;64148.67925660435;64259;64311.35688643147;68574.9713754294;68604;68704.96942545866;68762.65618671874;68777.96833047508;16733.916330418513;16989.575260618585;16990.745138822927;16997;17034.574135646708;11870.169088163746;12164.756704865853;12855.550055748192;12907.483700652057;12985.480580776852;14324.713505729827;17553.473395798148;17877.553061173316;18018.549536261697;18150.727739083923;6347.381417738447;6449.838754031093;6455.580387274812;6498.54510184292;6518.934810651927;955.7085089047907;1093.2074246171621;1096.15595991088;1111.2610114273916;1145.4616330324734;27806.582901256497;28063.43873122567;29932;30470.54294185589;30572.69427305743;1061.4639606998417;1073.313079629038;1114.788190243851;1125.5835340923911;1148.8047032004579;15418.614534636723;15445.768313475306;15501.379944802307;15802.130882801557;15898.966567173327;646634;656706;661724;662475.6876215588;662940;62770.68614656989;62845;62876.37123628797;62923.68538157371;62942.055869162;1330.481112366178;1368.322680273271;1371.991586184154;1482.0662982321069;1495.446684726658;30385.696143038287;31213.90751323693;31877.840610796797;32169.874054407985;32973;58716.70641646764;58947.70526147342;59637;59647;59696.70151649214;588594;593018;593993;594182;594578;34942.825285873405;35309.29381412409;39891.60108398879;40481.797591011855;40535.783926482254;40099.59651412186;44826.77586612046;47501.52498474971;48059.27911081267;49194;33713.651453941566;34419.827900860335;36375.63624363722;39184;40294.79852600718;29014;36339.091522712144;38155.790187002174;38327.80836095801;39017.219655607296;38185;38406.6159338403;38885.8055709731;39125.02187445336;39188;31482.425878706432;32468.350632986734;34003.319933600695;34004.65995340114;34024.97925062252;22406.215782447536;25067;25309.987600495773;25653.743462565137;27208.591871121553 03:28:10 INFO - PROCESS | 1664 | |2;jquery.html;378.292592851368;384.2698872142928;388.45422181834533;400.45338113475117;409.7930545074737;19832.405027849152;21576.352709418705;21945;21956;22006.899655017267;11600.129990250744;12224.266544007365;12232.755344893098;20476.795232047676;21417.9291035448;5220.321358223435;5329.680219186861;5375.973120134394;5389.51494365507;5432.239486471897;5239.21411788231;5351.759170837302;5375.354957405098;5397.433269506704;5412.377576578685;5313.548348390392;5402.78388864445;5411.458854114577;5412.404635490093;5424.945750542503;5249.842504724846;5382.730863456812;5384.165454354581;5388.084025715638;5413.810516631899;5012.799488020475;5230.555402790767;5262.631615786897;5265.973670131663;5270.525652691276;13158.289422375126;13574;19173.424797256106;21695.37284703638;24594.5081098377;2073.8651987620906;2121.1409379201295;2318.6058370076958;2386.7971222446113;2686.5835795451735;6437.935620643781;6456.806295811086;10085.596576137019;10106.39361638304;10314.226433017478;1699.141933323676;1699.4561740243128;1705.2411676803827;1720.053970316321;1817.954551136219;85440;125587;130358.99999999905;130993;134044;9515.429074255568;11480;11749.823752643717;11751.529938802352;11882.940585297018;15374.308156133;15467.375925527882;15508.5347439577;15515.913886027869;15519.612009699622;7647.502912310682;7652.120006199247;8087.47431416956;8276.503409795432;8451;8302.958485207535;8308.376871734645;8387.83224335522;8502.872456913152;8642;4576.519465456146;4676;4688.859334219979;4697.13103075932;4736.455307639666;1715.7426386041943;1757.578181236494;2000.0899590686204;2003.0785838514155;2007.6988451732357;880.3133555826465;977.3500622086233;1032.4115254304975;1041.401194313273;1074.9301295415773;19626.14971832522;19957.700634490495;20189.293374732108;20378.38864834028;20379;756.9735059272899;945.6170251048336;962.1196605106389;963.272729089533;986.8667729856521;8301.875471867806;10426.165906727443;10470.73823154412;10482.213833962483;10778.838317425245;541687;545741.271293641;562317;631808;632949;145512;154415;156508.21745891427;158476;159028;1023.3092662452964;1042.6611351310855;1080.8432777247413;1081.378207530673;1082.6643740440331;18118.637627247645;18498;18502.167402466654;18662.34681786131;18824.623507530046;46934.122635094216;54121.72939135279;55266;56835.71582142063;57222;73492;74569;76508.61745691235;78454.2154578447;82651.3469730614;8242.793930151794;8337.749867503984;8343.541105239028;8389.706360277361;8553.187447192508;9116.225120864536;11812.114091443003;11984.40077996114;13006.674833128869;13075.934620326838;9756.634071230175;9794.75513112178;11099.833502497308;11276.154288428237;11277;8445.746627601182;10863.076638485501;10889.564417423531;10981.341119532854;11256.662300131009;10996.615118470816;11012.284201526874;11408.258463199865;11580.42097895085;11619.47712352963;8098.473599216032;8129.634166462405;8287.41988060854;8401.579921004048;8572.871406928776;10578.259521833397;10771.138308935424;10941.781164376827;11175.38535380562;11208.103351732005 03:28:10 INFO - PROCESS | 1664 | |3;mootools.html;1388.7222555488904;1624.910629915355;1638.565780068282;1882.6611209982207;2009.8392128629703;7693.538387696735;8127.431079824415;8346.958265208681;8354.91645083549;8457.661693532267;8109.513429194252;8312.501249925008;8319.960004999375;8327.750167494969;8336.499810011404;1926.9594419013715;2081.5845225246826;2331.3006098170567;2380.4048987753063;2411.710594728635;2430.80553555716;2442.9022839086415;2450.3261603059213;2456.6437866509345;2459.2622213336017;2369.0286982337243;2393.1384701507445;2411.360989337827;2412.191915708231;2414.468816860288;2080.865928069198;2300.723913130418;2348.2250857217164;2382.1781485387514;2389.545986262622;2410.746871578494;2423.575874222004;2429.9028038878423;2430.1373012580634;2431.7081950166094;8619.396642235046;8695.69565065226;8696.652133914698;8705.216530512283;8710.520921349324;5112.207607820794;5119.155339368999;5317.122674758699;5348.91976620351;5360.437154098802;3364.6971772540587;3410.9829450852835;3508.6315936826522;3518.9472157917653;3540.2211513467255;851.6210286422545;923.8244733500613;991.8264303746888;1004.9397036177853;1036.5750042482546;11487.597934072268;11497.770044599061;11512.309261444256;11524.42377881111;11532.481038353295;4335.328024156261;4437.112577484516;4507.549245075466;4519.299508576176;4536.931946020813;9754;9857.75355616101;9860.16188623975;9870.210383169473;9872.210223182128;5159.406668233126;5192.584593232532;5196.350456192976;5198.2202669599865;5202.583793296527;4376.8905777355185;4559.680822342404;4775.2598347256235;4899.216125419915;4903.485134060944;3287.7862938908893;3946.940795888064;4034.1326614777377;4061.268971585084;4075.673946084306;1099.2689861242345;1115.8005144469728;1321.4119716725966;1339.8459177194748;1345.4550906882728;921.1295325916944;928.2713070239829;930.7673081729567;943.5329511891657;988.5650313861878;5053.974730126326;5856.267966504187;5954.047352423678;5975;6147;457.25924003115165;457.58817064641426;460.2337108713954;471.91930479202927;478.841982145891;3958.0302825807285;3979.1245925896255;4066.552679205285;4077.8368865245056;4080.6123418275215;9791.804163916824;10549.419781912069;10549.578016879392;10573.418461984666;10661.520231589597;10502.264841461025;10527.789444211074;10590.523426445829;10608.310459820086;10611.893881061245;8004.5597492138495;8143.226393492609;8242.216989386;8248.587570621445;8261.793455163548;5802.071668533184;5810.6513609183585;5832.504237139721;5861.589688721749;5926.911096333644;7742.8451430969935;9723.124918757347;9755.560999754885;9782.9021709782;9896.901030989886;9676.806463870824;9693.127618514367;9903.900960990299;10008.949955250177;10086.899131008597;6795.728170873307;7026.156861176692;7206.4234861209925;7483.438742094474;7572.129205141479;7955.681772729026;8195.688689809602;8433.114522975;8462.619182136696;8463.534505602005;7251.057362542836;7370.520916140434;7506.24937506245;7605.353544948742;7687.461877668509;7213.458990575833;7372.336489715952;7388.224236455309;7492.925070749441;7531.962340188264;6978.825529361807;7001.089858318386;7045.929540704732;7057.682404291717;7078.18600860887;4821.228603423436;4911.361523001987;5020.196768517019;5040.521150490697;5215.13950198213;4961.479044700254;5037.0681423936685;5056.974715126401;5131.8717032073;5320.414754376938 03:28:10 INFO - PROCESS | 1664 | |4;prototype.html;305.70041359467723;307.5755457468693;319.24498560903464;324.45815488134815;327.4433463112709;4703.5061318561575;5319.537127289997;5320.4679532046775;5387.16498942664;5404.0545403654705;3956.4065390191486;4573.931391029138;4998.350214472113;5291.656042357254;5294.3587487878685;3405.5990526678634;4035.757854528723;4040.474738284019;4040.969273045216;4082.4486695055984;3377.020664007435;3850.4801851750076;4048.2510735514024;4085.387191921206;4093.7543747375103;3767.9811600941957;3810.580836108026;3827.770333779982;3957.94187022748;4010.2981978153853;3509.7543171977973;3512.455569386749;3993.1813978134414;4004.659603933669;4065.674746020326;3495.196104895863;3509.175343794206;3533.0460775590445;3547.740552103992;3582.0328511302064;4485.416895803557;4661.930071048903;4891.437484689271;5054.545090941796;5122.513361230677;3542.8582856685703;4274.316109422478;4303.720258183208;4517.390152329426;4629.819396054018;5566.415526369713;5589.944100558983;5595.300587426572;5597.608167428281;5613.270274864282;3490.912727181815;3745.7190710696536;3882.437046628223;3893.5717071122153;4069.9186016279805;4855.5236829217;5852.736626851802;5858.970705146447;5872.29532456108;5891.293044834604;3931.3881308663313;3950.2692001979726;3969.2656858480696;3972.801359931992;3983.7052957788255;3644.7972169184104;3830.71269654777;3993.5806740292433;4022.3162062449455;4050.432939388488;4240.681948853787;4452.977735111303;4571.519990401027;4594.632429405639;4667.369905062841;4829.468758436569;4867.269909513601;5356.268303165413;5579.358373786985;5632.098864181711;1207.124834494984;1266.069438962362;1302.5180683147296;1302.742853146718;1314.309987256698;794.4597673582019;841.8484672758963;851.6721062391024;867.5488745852122;892.8393088984294;4245.919499445249;4450.777461126931;4476.776161191928;4939.987302602958;4941.283513890466;4473.552644735565;4663.813447462131;5436.592255580768;5490.945090549043;5527.977324194956;4128.896777580585;4312.396264522969;4753.215719406259;4928;4987.226979818134;2557.7058638256467;2621.9606705899428;2893.074216250822;2931.4137172565147;2950.0971873142134;5197.272381866542;5250.737463126905;5504.53211477029;5746.482816546531;5842.620229685057;5383.838484845461;5421.241026256327;5678.750674851526;5720.3421606515785;5733.222700962631;5190.844274671766;5332.466753324714;5696.601237913306;5698.3162020556965;5721.799737009184;4999.550040496373;5031.597472202288;5548.611597188158;5627.352854421631;5635.393348257036;4889.584385327287;5148.76830542619;5264.578833693372;5490.203920431435;5520.199571062254;5183.0912759455005;5423.8541645844525;5561.888762224652;5746.224259724883;5847.649141051551;3219.5814544109116;3637.9818100909324;3673.687736542424;3673.779573225615;3692.390755525308;4593;4953.281774142802;5528.778848846001;5574.749136288796;5605.971970140124;5311.256424100552;5369.301990741179;5415.702136382537;5444.5916556259235;5527.1258945349;5105.617078719037;5161.458046905021;5513.842093160481;5614.382417933943;5639.351474580476;4483.663725220556;4971.65198436106;5456.345238571397;5482.506574408322;5503.697296648563;4370.519242883217;5559.805406810742;5565.821937649287;5580.804671836466;5592.86017849557;4260.595243451867;5157.561607263425;5246.606504512253;5440.483154100354;5508.531774799187 03:28:10 INFO - PROCESS | 1664 | |5;yui.html;581.3779256195871;665.9400653941148;715.7423327602062;726.5422783646304;757.8446418484211;13444.731105377887;14509.201993890332;14960.700785984274;15041.999999999985;15520.844791552081;14214.076085054465;14281.92859035706;14290.428382864673;14296.857031429683;14305.427782888672;709.4749885085025;884.4428010353469;900.6307413960278;902.7878448564582;915.771057235691;871.3682580129387;882.6292956958093;886.1537231943479;887.2591386192537;888.1340692824509;749.6251874062968;939.976500587484;942.726609283307;942.9339946203769;943.273679266964;929.5677509957868;945.8250223708636;948.1040416806121;960.3661583354952;962.129273007929;721.0374150509076;722.7181399254295;722.7325889420948;723.1791916175126;723.2514347650157;14567.490137845125;14577.927110364486;14615.853841461556;14627.561173164822;14634.121952682874;10755;12148.514059437612;12238.143329966906;12319.199252048678;12495.687607809878;10484.633037843716;10530.68407947755;10838.295510791773;10870.159226966785;11123.666290011231;3942.8225729842225;3965.920681586352;3976.7415118017525;3978.423128646359;3987.4616926715103;66804.6659766703;68839.27901802445;69195.65402172957;77136.61431692862;77303.84044239341;8981.955090224506;9248.7687807804;9283.303752218611;9641.710748677551;9687.806243875082;20720.171193152106;20954.580908382053;20981;20994.580108397746;21014.89492552558;7189.065421495172;7268.236835132235;7378;7378.778636640909;7500.699972001168;4071.97964010184;4115.814788334532;4127.772972486482;4139.379093136054;4154.8338066477;3437.759356845047;3565.0017994961454;3567.2687099144614;3573.213892943548;3577.0520811984843;568.6076607141059;576.6684156609966;595.5943972225544;601.5097695378253;661.6030381770995;506.50615649741206;529.751017021999;568.8321945026228;590.9556783241275;592.754007087054;7075.186353569303;8027.879581806277;8068.9596552016865;8123.350131989427;8147.103818579952;531.7421050790364;540.6053580885897;595.3034949109552;613.1354789746437;617.4350469320611;3780.8676696315497;3896.649301562816;3944.4280579315837;3976.840926362971;3977.76133431995;99615.5173017216;99910.50044749875;101086.98913010923;102724;104732;60584.394156057875;84027;85402;88465.55767221123;88833;79449.60275198588;80614;80751;80789.59605201936;81314.18685813184;7370;7489.438292128003;7587.317141457295;7595.734149304748;7685.353073691713;38736.80631596824;45331.61365147458;47753.522464776295;48072;48424.757876210395;46923.53076469192;47097;47889;64922.727704530116;65107.04678859642;9006.324525660471;10860.348379097279;10936.78126437451;11582.131340149353;12221.572244971383;11819.40902954866;12164.817527737268;12240.204386714835;12442.315672638091;12465.750684986431;11163.944180279372;11223.494942727433;11268.943655281671;11562.30626162433;11838;11434.828477572677;11589.594364197214;11767.941160294486;11959.940200298943;12265.448054837732;11100.167487438313;11122.44387780624;11235.831462527905;11348.092152627605;12080.818787718366;9005.23455506263;9015.078281494276;9064.18422341966;9280.264382518675;9459;8717.215450609221;8892.714282871466;9551.713448596553;9767.462789546644;9814.178249389784 03:28:10 INFO - PROCESS | 1664 | __end_tp_report 03:28:10 INFO - PROCESS | 1664 | __start_cc_report 03:28:10 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1556 03:28:10 INFO - PROCESS | 1664 | __end_cc_report 03:28:10 INFO - PROCESS | 1664 | __startTimestamp1462876090559__endTimestamp 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:28:10 INFO - PROCESS | 1664 | Number of tests: 6 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5706.56 Median:5820.68 stddev:646.40 (11.1%) stddev-sans-first:618.35 03:28:10 INFO - PROCESS | 1664 | Values: 3224.0 3456.6 3466.5 3481.5 3499.8 5586.5 5604.7 5626.7 5672.8 5697.7 5616.2 5682.7 5683.0 5699.9 5704.7 5175.9 5208.6 5240.0 5242.6 5316.0 5224.9 5248.0 5265.3 5268.9 5272.0 5149.2 5158.0 5170.0 5172.6 5272.3 5237.1 5286.2 5288.8 5298.8 5333.4 4212.9 5086.7 5089.3 5132.6 5206.7 5523.0 5530.7 5553.8 5572.2 5573.0 6242.6 6255.2 6258.3 6273.9 6279.4 6114.5 6119.4 6134.7 6278.8 6313.4 4705.4 5690.6 5714.9 5737.1 5748.5 6290.7 6295.0 6297.6 6318.9 6330.2 5957.3 5980.6 6058.5 6125.6 6159.9 6274.0 6280.9 6290.2 6293.2 6360.8 6197.9 6240.2 6250.0 6264.0 6289.0 6127.2 6153.6 6167.8 6209.5 6268.2 4869.6 4948.3 4957.5 4958.3 4974.1 4427.6 4442.4 4453.3 4469.7 4491.2 5698.7 5722.2 5727.0 5727.7 5736.6 6102.3 6161.3 6199.9 6200.5 6221.9 5699.4 5703.8 5704.4 5712.0 5737.2 5383.6 5401.7 5404.7 5413.9 5424.8 4667.5 5107.7 6270.2 6335.4 6340.0 6252.4 6265.8 6285.7 6305.6 6333.2 6317.1 6341.1 6355.3 6355.5 6376.4 6007.0 6024.1 6101.1 6186.5 6204.0 6188.2 6214.1 6220.5 6243.2 6243.4 6116.6 6134.7 6139.0 6142.1 6160.9 6105.8 6117.1 6122.6 6123.6 6126.1 5766.6 5820.7 5849.2 5925.4 5934.5 6071.2 6092.8 6117.6 6124.9 6132.1 5302.9 5702.4 5760.3 5802.6 5914.2 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:72198.39 Median:36357.36 stddev:155677.04 (428.2%) stddev-sans-first:156020.67 03:28:10 INFO - PROCESS | 1664 | Values: 494.1 526.5 535.9 563.5 572.8 40475.0 43050.4 43147.8 43183.1 43227.0 43568.6 44301.6 44312.0 44315.3 44378.0 8753.8 8925.4 8963.0 9005.0 9692.5 8811.3 11781.9 12064.4 12069.2 12199.5 11867.0 11889.4 11897.7 11914.8 11919.0 8862.1 11892.5 11917.7 11981.6 11982.1 11546.2 11846.4 11878.0 11881.4 11885.8 40784.6 41260.2 41281.4 41293.4 41369.6 3186.7 3236.6 3351.8 3809.4 4099.9 29907.1 30519.8 30524.5 30784.0 30835.1 2711.3 2758.4 2783.7 2845.4 2862.9 483235.0 483838.0 485054.0 485268.0 485873.0 63863.4 63968.0 64148.7 64259.0 64311.4 68575.0 68604.0 68705.0 68762.7 68778.0 16733.9 16989.6 16990.7 16997.0 17034.6 11870.2 12164.8 12855.6 12907.5 12985.5 14324.7 17553.5 17877.6 18018.5 18150.7 6347.4 6449.8 6455.6 6498.5 6518.9 955.7 1093.2 1096.2 1111.3 1145.5 27806.6 28063.4 29932.0 30470.5 30572.7 1061.5 1073.3 1114.8 1125.6 1148.8 15418.6 15445.8 15501.4 15802.1 15899.0 646634.0 656706.0 661724.0 662475.7 662940.0 62770.7 62845.0 62876.4 62923.7 62942.1 1330.5 1368.3 1372.0 1482.1 1495.4 30385.7 31213.9 31877.8 32169.9 32973.0 58716.7 58947.7 59637.0 59647.0 59696.7 588594.0 593018.0 593993.0 594182.0 594578.0 34942.8 35309.3 39891.6 40481.8 40535.8 40099.6 44826.8 47501.5 48059.3 49194.0 33713.7 34419.8 36375.6 39184.0 40294.8 29014.0 36339.1 38155.8 38327.8 39017.2 38185.0 38406.6 38885.8 39125.0 39188.0 31482.4 32468.4 34003.3 34004.7 34025.0 22406.2 25067.0 25310.0 25653.7 27208.6 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34966.78 Median:2352.70 stddev:98707.42 (4195.5%) stddev-sans-first:98950.15 03:28:10 INFO - PROCESS | 1664 | Values: 378.3 384.3 388.5 400.5 409.8 19832.4 21576.4 21945.0 21956.0 22006.9 11600.1 12224.3 12232.8 20476.8 21417.9 5220.3 5329.7 5376.0 5389.5 5432.2 5239.2 5351.8 5375.4 5397.4 5412.4 5313.5 5402.8 5411.5 5412.4 5424.9 5249.8 5382.7 5384.2 5388.1 5413.8 5012.8 5230.6 5262.6 5266.0 5270.5 13158.3 13574.0 19173.4 21695.4 24594.5 2073.9 2121.1 2318.6 2386.8 2686.6 6437.9 6456.8 10085.6 10106.4 10314.2 1699.1 1699.5 1705.2 1720.1 1818.0 85440.0 125587.0 130359.0 130993.0 134044.0 9515.4 11480.0 11749.8 11751.5 11882.9 15374.3 15467.4 15508.5 15515.9 15519.6 7647.5 7652.1 8087.5 8276.5 8451.0 8303.0 8308.4 8387.8 8502.9 8642.0 4576.5 4676.0 4688.9 4697.1 4736.5 1715.7 1757.6 2000.1 2003.1 2007.7 880.3 977.4 1032.4 1041.4 1074.9 19626.1 19957.7 20189.3 20378.4 20379.0 757.0 945.6 962.1 963.3 986.9 8301.9 10426.2 10470.7 10482.2 10778.8 541687.0 545741.3 562317.0 631808.0 632949.0 145512.0 154415.0 156508.2 158476.0 159028.0 1023.3 1042.7 1080.8 1081.4 1082.7 18118.6 18498.0 18502.2 18662.3 18824.6 46934.1 54121.7 55266.0 56835.7 57222.0 73492.0 74569.0 76508.6 78454.2 82651.3 8242.8 8337.7 8343.5 8389.7 8553.2 9116.2 11812.1 11984.4 13006.7 13075.9 9756.6 9794.8 11099.8 11276.2 11277.0 8445.7 10863.1 10889.6 10981.3 11256.7 10996.6 11012.3 11408.3 11580.4 11619.5 8098.5 8129.6 8287.4 8401.6 8572.9 10578.3 10771.1 10941.8 11175.4 11208.1 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5617.86 Median:5084.59 stddev:3140.44 (61.8%) stddev-sans-first:3133.17 03:28:10 INFO - PROCESS | 1664 | Values: 1388.7 1624.9 1638.6 1882.7 2009.8 7693.5 8127.4 8347.0 8354.9 8457.7 8109.5 8312.5 8320.0 8327.8 8336.5 1927.0 2081.6 2331.3 2380.4 2411.7 2430.8 2442.9 2450.3 2456.6 2459.3 2369.0 2393.1 2411.4 2412.2 2414.5 2080.9 2300.7 2348.2 2382.2 2389.5 2410.7 2423.6 2429.9 2430.1 2431.7 8619.4 8695.7 8696.7 8705.2 8710.5 5112.2 5119.2 5317.1 5348.9 5360.4 3364.7 3411.0 3508.6 3518.9 3540.2 851.6 923.8 991.8 1004.9 1036.6 11487.6 11497.8 11512.3 11524.4 11532.5 4335.3 4437.1 4507.5 4519.3 4536.9 9754.0 9857.8 9860.2 9870.2 9872.2 5159.4 5192.6 5196.4 5198.2 5202.6 4376.9 4559.7 4775.3 4899.2 4903.5 3287.8 3946.9 4034.1 4061.3 4075.7 1099.3 1115.8 1321.4 1339.8 1345.5 921.1 928.3 930.8 943.5 988.6 5054.0 5856.3 5954.0 5975.0 6147.0 457.3 457.6 460.2 471.9 478.8 3958.0 3979.1 4066.6 4077.8 4080.6 9791.8 10549.4 10549.6 10573.4 10661.5 10502.3 10527.8 10590.5 10608.3 10611.9 8004.6 8143.2 8242.2 8248.6 8261.8 5802.1 5810.7 5832.5 5861.6 5926.9 7742.8 9723.1 9755.6 9782.9 9896.9 9676.8 9693.1 9903.9 10008.9 10086.9 6795.7 7026.2 7206.4 7483.4 7572.1 7955.7 8195.7 8433.1 8462.6 8463.5 7251.1 7370.5 7506.2 7605.4 7687.5 7213.5 7372.3 7388.2 7492.9 7532.0 6978.8 7001.1 7045.9 7057.7 7078.2 4821.2 4911.4 5020.2 5040.5 5215.1 4961.5 5037.1 5057.0 5131.9 5320.4 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4404.72 Median:4890.51 stddev:1345.14 (27.5%) stddev-sans-first:1313.25 03:28:10 INFO - PROCESS | 1664 | Values: 305.7 307.6 319.2 324.5 327.4 4703.5 5319.5 5320.5 5387.2 5404.1 3956.4 4573.9 4998.4 5291.7 5294.4 3405.6 4035.8 4040.5 4041.0 4082.4 3377.0 3850.5 4048.3 4085.4 4093.8 3768.0 3810.6 3827.8 3957.9 4010.3 3509.8 3512.5 3993.2 4004.7 4065.7 3495.2 3509.2 3533.0 3547.7 3582.0 4485.4 4661.9 4891.4 5054.5 5122.5 3542.9 4274.3 4303.7 4517.4 4629.8 5566.4 5589.9 5595.3 5597.6 5613.3 3490.9 3745.7 3882.4 3893.6 4069.9 4855.5 5852.7 5859.0 5872.3 5891.3 3931.4 3950.3 3969.3 3972.8 3983.7 3644.8 3830.7 3993.6 4022.3 4050.4 4240.7 4453.0 4571.5 4594.6 4667.4 4829.5 4867.3 5356.3 5579.4 5632.1 1207.1 1266.1 1302.5 1302.7 1314.3 794.5 841.8 851.7 867.5 892.8 4245.9 4450.8 4476.8 4940.0 4941.3 4473.6 4663.8 5436.6 5490.9 5528.0 4128.9 4312.4 4753.2 4928.0 4987.2 2557.7 2622.0 2893.1 2931.4 2950.1 5197.3 5250.7 5504.5 5746.5 5842.6 5383.8 5421.2 5678.8 5720.3 5733.2 5190.8 5332.5 5696.6 5698.3 5721.8 4999.6 5031.6 5548.6 5627.4 5635.4 4889.6 5148.8 5264.6 5490.2 5520.2 5183.1 5423.9 5561.9 5746.2 5847.6 3219.6 3638.0 3673.7 3673.8 3692.4 4593.0 4953.3 5528.8 5574.7 5606.0 5311.3 5369.3 5415.7 5444.6 5527.1 5105.6 5161.5 5513.8 5614.4 5639.4 4483.7 4971.7 5456.3 5482.5 5503.7 4370.5 5559.8 5565.8 5580.8 5592.9 4260.6 5157.6 5246.6 5440.5 5508.5 03:28:10 INFO - PROCESS | 1664 | 03:28:10 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18153.86 Median:572.75 stddev:26172.03 (4569.5%) stddev-sans-first:26212.18 03:28:10 INFO - PROCESS | 1664 | Values: 581.4 665.9 715.7 726.5 757.8 13444.7 14509.2 14960.7 15042.0 15520.8 14214.1 14281.9 14290.4 14296.9 14305.4 709.5 884.4 900.6 902.8 915.8 871.4 882.6 886.2 887.3 888.1 749.6 940.0 942.7 942.9 943.3 929.6 945.8 948.1 960.4 962.1 721.0 722.7 722.7 723.2 723.3 14567.5 14577.9 14615.9 14627.6 14634.1 10755.0 12148.5 12238.1 12319.2 12495.7 10484.6 10530.7 10838.3 10870.2 11123.7 3942.8 3965.9 3976.7 3978.4 3987.5 66804.7 68839.3 69195.7 77136.6 77303.8 8982.0 9248.8 9283.3 9641.7 9687.8 20720.2 20954.6 20981.0 20994.6 21014.9 7189.1 7268.2 7378.0 7378.8 7500.7 4072.0 4115.8 4127.8 4139.4 4154.8 3437.8 3565.0 3567.3 3573.2 3577.1 568.6 576.7 595.6 601.5 661.6 506.5 529.8 568.8 591.0 592.8 7075.2 8027.9 8069.0 8123.4 8147.1 531.7 540.6 595.3 613.1 617.4 3780.9 3896.6 3944.4 3976.8 3977.8 99615.5 99910.5 101087.0 102724.0 104732.0 60584.4 84027.0 85402.0 88465.6 88833.0 79449.6 80614.0 80751.0 80789.6 81314.2 7370.0 7489.4 7587.3 7595.7 7685.4 38736.8 45331.6 47753.5 48072.0 48424.8 46923.5 47097.0 47889.0 64922.7 65107.0 9006.3 10860.3 10936.8 11582.1 12221.6 11819.4 12164.8 12240.2 12442.3 12465.8 11163.9 11223.5 11268.9 11562.3 11838.0 11434.8 11589.6 11767.9 11959.9 12265.4 11100.2 11122.4 11235.8 11348.1 12080.8 9005.2 9015.1 9064.2 9280.3 9459.0 8717.2 8892.7 9551.7 9767.5 9814.2 03:28:10 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:28:10 INFO - PROCESS | 1664 | 03:28:11 INFO - TEST-INFO | 1664: exit 0 03:28:11 INFO - TEST-OK | dromaeo_css | took 1116729ms 03:28:11 INFO - TEST-START | kraken 03:28:11 INFO - operating with platform_type : mac_ 03:28:11 INFO - Initialising browser for kraken test... 03:28:11 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpFqqBN8/profile http://localhost:49275/getInfo.html) 03:28:13 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 03:28:13 INFO - PROCESS | 1671 | colorDepth:24 03:28:13 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 03:28:13 INFO - PROCESS | 1671 | __metrics 03:28:13 INFO - PROCESS | 1671 | 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" 03:28:13 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:28:14 INFO - TEST-INFO | 1671: exit 0 03:28:14 INFO - Browser initialized. 03:28:14 INFO - Running cycle 1/1 for kraken test... 03:28:14 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpFqqBN8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:26 INFO - PROCESS | 1673 | RSS: Main: 239046656 03:28:26 INFO - PROCESS | 1673 | 03:28:28 INFO - PROCESS | 1673 | 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) 03:28:29 INFO - PROCESS | 1673 | RSS: Main: 288153600 03:28:29 INFO - PROCESS | 1673 | 03:28:32 INFO - PROCESS | 1673 | 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) 03:28:33 INFO - PROCESS | 1673 | RSS: Main: 296271872 03:28:33 INFO - PROCESS | 1673 | 03:28:36 INFO - PROCESS | 1673 | 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) 03:28:37 INFO - PROCESS | 1673 | RSS: Main: 384659456 03:28:37 INFO - PROCESS | 1673 | 03:28:39 INFO - PROCESS | 1673 | 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) 03:28:40 INFO - PROCESS | 1673 | RSS: Main: 284717056 03:28:40 INFO - PROCESS | 1673 | 03:28:42 INFO - PROCESS | 1673 | 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) 03:28:43 INFO - PROCESS | 1673 | RSS: Main: 288215040 03:28:43 INFO - PROCESS | 1673 | 03:28:45 INFO - PROCESS | 1673 | 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) 03:28:46 INFO - PROCESS | 1673 | RSS: Main: 326594560 03:28:46 INFO - PROCESS | 1673 | 03:28:49 INFO - PROCESS | 1673 | 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) 03:28:50 INFO - PROCESS | 1673 | RSS: Main: 329334784 03:28:50 INFO - PROCESS | 1673 | 03:28:53 INFO - PROCESS | 1673 | 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) 03:28:54 INFO - PROCESS | 1673 | RSS: Main: 328818688 03:28:54 INFO - PROCESS | 1673 | 03:28:55 INFO - PROCESS | 1673 | 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) 03:28:56 INFO - PROCESS | 1673 | RSS: Main: 316563456 03:28:56 INFO - PROCESS | 1673 | 03:28:58 INFO - PROCESS | 1673 | 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) 03:28:58 INFO - PROCESS | 1673 | RSS: Main: 323731456 03:28:58 INFO - PROCESS | 1673 | 03:29:01 INFO - PROCESS | 1673 | 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) 03:29:02 INFO - PROCESS | 1673 | RSS: Main: 325083136 03:29:02 INFO - PROCESS | 1673 | 03:29:05 INFO - PROCESS | 1673 | 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) 03:29:05 INFO - PROCESS | 1673 | RSS: Main: 329969664 03:29:05 INFO - PROCESS | 1673 | 03:29:08 INFO - PROCESS | 1673 | 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) 03:29:09 INFO - PROCESS | 1673 | RSS: Main: 327344128 03:29:09 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | 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) 03:29:11 INFO - PROCESS | 1673 | RSS: Main: 374358016 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | __start_tp_report 03:29:11 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 03:29:11 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 03:29:11 INFO - PROCESS | 1673 | |i|pagename|runs| 03:29:11 INFO - PROCESS | 1673 | |0;ai-astar;111;104;112;113;111;112;112;113;111;111 03:29:11 INFO - PROCESS | 1673 | |1;audio-beat-detection;156;119;149;246;118;142;137;118;171;121 03:29:11 INFO - PROCESS | 1673 | |2;audio-dft;185;185;186;186;182;187;188;185;186;184 03:29:11 INFO - PROCESS | 1673 | |3;audio-fft;81;73;59;156;81;91;58;59;111;56 03:29:11 INFO - PROCESS | 1673 | |4;audio-oscillator;90;101;134;81;81;78;134;116;78;101 03:29:11 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;95;87;90;91;92;85;88;91;87;83 03:29:11 INFO - PROCESS | 1673 | |6;imaging-darkroom;136;111;101;126;93;93;94;93;93;95 03:29:11 INFO - PROCESS | 1673 | |7;imaging-desaturate;84;83;83;86;83;83;82;83;85;83 03:29:11 INFO - PROCESS | 1673 | |8;json-parse-financial;60;59;61;57;59;59;60;123;60;59 03:29:11 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;70;67;71;72;69;65;66;69;72 03:29:11 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;87;107;109;106;86;86;82;84;94;107 03:29:11 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;142;174;151;148;146;140;142;153;253;141 03:29:11 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;171;224;181;173;168;168;170;171;176;221 03:29:11 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;68;67;63;78;83;86;87;69;66 03:29:11 INFO - PROCESS | 1673 | __end_tp_report 03:29:11 INFO - PROCESS | 1673 | __start_cc_report 03:29:11 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2237 03:29:11 INFO - PROCESS | 1673 | __end_cc_report 03:29:11 INFO - PROCESS | 1673 | __startTimestamp1462876151773__endTimestamp 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | ------- Summary: start ------- 03:29:11 INFO - PROCESS | 1673 | Number of tests: 14 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:2.58 (2.3%) stddev-sans-first:2.74 03:29:11 INFO - PROCESS | 1673 | Values: 111.0 104.0 112.0 113.0 111.0 112.0 112.0 113.0 111.0 111.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:145.50 stddev:39.05 (26.8%) stddev-sans-first:41.30 03:29:11 INFO - PROCESS | 1673 | Values: 156.0 119.0 149.0 246.0 118.0 142.0 137.0 118.0 171.0 121.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:1.65 (0.9%) stddev-sans-first:1.74 03:29:11 INFO - PROCESS | 1673 | Values: 185.0 185.0 186.0 186.0 182.0 187.0 188.0 185.0 186.0 184.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:82.50 Median:66.00 stddev:31.25 (47.3%) stddev-sans-first:33.14 03:29:11 INFO - PROCESS | 1673 | Values: 81.0 73.0 59.0 156.0 81.0 91.0 58.0 59.0 111.0 56.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:99.40 Median:78.00 stddev:22.00 (28.2%) stddev-sans-first:23.07 03:29:11 INFO - PROCESS | 1673 | Values: 90.0 101.0 134.0 81.0 81.0 78.0 134.0 116.0 78.0 101.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.50 stddev:3.57 (3.9%) stddev-sans-first:3.03 03:29:11 INFO - PROCESS | 1673 | Values: 95.0 87.0 90.0 91.0 92.0 85.0 88.0 91.0 87.0 83.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:93.00 stddev:15.74 (16.9%) stddev-sans-first:11.48 03:29:11 INFO - PROCESS | 1673 | Values: 136.0 111.0 101.0 126.0 93.0 93.0 94.0 93.0 93.0 95.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:83.50 Median:83.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 03:29:11 INFO - PROCESS | 1673 | Values: 84.0 83.0 83.0 86.0 83.0 83.0 82.0 83.0 85.0 83.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:59.50 stddev:20.16 (33.9%) stddev-sans-first:21.28 03:29:11 INFO - PROCESS | 1673 | Values: 60.0 59.0 61.0 57.0 59.0 59.0 60.0 123.0 60.0 59.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:69.50 stddev:3.06 (4.4%) stddev-sans-first:2.55 03:29:11 INFO - PROCESS | 1673 | Values: 63.0 70.0 67.0 71.0 72.0 69.0 65.0 66.0 69.0 72.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:85.00 stddev:11.16 (13.1%) stddev-sans-first:11.48 03:29:11 INFO - PROCESS | 1673 | Values: 87.0 107.0 109.0 106.0 86.0 86.0 82.0 84.0 94.0 107.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:149.50 stddev:34.50 (23.1%) stddev-sans-first:36.04 03:29:11 INFO - PROCESS | 1673 | Values: 142.0 174.0 151.0 148.0 146.0 140.0 142.0 153.0 253.0 141.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.30 Median:174.50 stddev:21.55 (12.4%) stddev-sans-first:22.47 03:29:11 INFO - PROCESS | 1673 | Values: 171.0 224.0 181.0 173.0 168.0 168.0 170.0 171.0 176.0 221.0 03:29:11 INFO - PROCESS | 1673 | 03:29:11 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.50 stddev:9.21 (12.5%) stddev-sans-first:9.39 03:29:11 INFO - PROCESS | 1673 | Values: 66.0 68.0 67.0 63.0 78.0 83.0 86.0 87.0 69.0 66.0 03:29:11 INFO - PROCESS | 1673 | -------- Summary: end -------- 03:29:11 INFO - PROCESS | 1673 | 03:29:12 INFO - TEST-INFO | 1673: exit 0 03:29:12 INFO - TEST-OK | kraken | took 61469ms 03:29:12 INFO - SUITE-END | took 1178s 03:29:12 INFO - Completed test suite (00:19:38) 03:29:12 INFO - javascript benchmark 03:29:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3223.9838800806, 3456.6197718251005, 3466.4800279958013, 3481.5125882376487, 3499.7575860569495, 5586.469285417878, 5604.747786349614, 5626.662400255982, 5672.829815105543, 5697.6866272355, 5616.241807356006, 5682.659040457565, 5682.971585142079, 5699.914501282474, 5704.743286552105, 5175.870603234921, 5208.635395522315, 5239.973800130994, 5242.580593552507, 5316.0, 5224.895502089956, 5247.973760131213, 5265.289185959904, 5268.894622107556, 5272.024675435056, 5149.17613181891, 5158.019976204528, 5169.991851588941, 5172.612054095939, 5272.314599102111, 5237.109691352479, 5286.233496143038, 5288.81489147878, 5298.761555730001, 5333.413324534298, 4212.85255016073, 5086.669366491166, 5089.312942752718, 5132.563732082777, 5206.661566998133, 5523.005858945388, 5530.668159910378, 5553.750081246313, 5572.192032155355, 5573.0, 6242.5942313749465, 6255.186825712673, 6258.34287399821, 6273.8745225095245, 6279.403456671609, 6114.510272098682, 6119.41115851624, 6134.69326533676, 6278.843028924268, 6313.43179113882, 4705.364775755298, 5690.630109042858, 5714.942850571482, 5737.139429085671, 5748.540116790647, 6290.654014029273, 6294.961331380271, 6297.559170858088, 6318.905216421757, 6330.177076980055, 5957.344692083867, 5980.611260268069, 6058.545609079337, 6125.632462052202, 6159.938400616026, 6274.0, 6280.905786413207, 6290.24517057956, 6293.244810622663, 6360.809175724736, 6197.938020619827, 6240.157578726905, 6250.0, 6263.96868015657, 6288.968555157287, 6127.18952814863, 6153.5692501525355, 6167.753289868355, 6209.5032397408095, 6268.153799237136, 4869.586085182799, 4948.282499037691, 4957.504249575014, 4958.305837182797, 4974.129527332702, 4427.6236519896165, 4442.422485076984, 4453.3320001999955, 4469.709468884577, 4491.236489796743, 5698.686572238484, 5722.198892155102, 5726.9713651431475, 5727.684977326204, 5736.598438109292, 6102.337773232528, 6161.260648722182, 6199.938000620026, 6200.503959683215, 6221.875562488724, 5699.401562835843, 5703.800366987136, 5704.429557044263, 5711.9714401428555, 5737.225474560964, 5383.596230282745, 5401.675899446046, 5404.729763511808, 5413.918791218135, 5424.755885985141, 4667.463241727245, 5107.693538387634, 6270.15352927349, 6335.366463353628, 6340.0, 6252.437280644582, 6265.8120256392385, 6285.654289014148, 6305.621662700252, 6333.20834895638, 6317.084022816574, 6341.143945567474, 6355.3326900674765, 6355.459785918249, 6376.3623637635865, 6007.038873780174, 6024.1265016573225, 6101.115338275837, 6186.536009799193, 6203.96898015507, 6188.164597779242, 6214.098955651496, 6220.502359811295, 6243.219597550306, 6243.406876346739, 6116.625929066835, 6134.681512609229, 6139.032198454696, 6142.0997666483, 6160.938390616216, 6105.793350785129, 6117.051856962178, 6122.602030867979, 6123.557162997564, 6126.059675755565, 5766.567507437043, 5820.679862607597, 5849.210356601804, 5925.377835327228, 5934.495567876779, 6071.150038994456, 6092.780947655669, 6117.571769976059, 6124.938750612437, 6132.110843927695, 5302.8939421212135, 5702.403925264482, 5760.337561180518, 5802.622829516068, 5914.201582786269], "value": 5666.819094876839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.1401960588576, 526.4682670502791, 535.8719894621823, 563.4534501533514, 572.7909313100714, 40475.0, 43050.35424468628, 43147.784261078654, 43183.136337273274, 43227.0, 43568.564314356845, 44301.55698443014, 44312.0, 44315.33526997089, 44378.002989865454, 8753.7811554711, 8925.41984770991, 8962.955185224066, 9004.954975225115, 9692.466914319728, 8811.339149563777, 11781.882181178164, 12064.396780161002, 12069.155159138863, 12199.512019519208, 11867.0, 11889.405529723523, 11897.70255743609, 11914.821277680841, 11919.0, 8862.069482704288, 11892.524299028071, 11917.702057448632, 11981.580644677395, 11982.101342399312, 11546.249493782878, 11846.407679616072, 11878.0, 11881.40592970348, 11885.762284754257, 40784.59215407867, 41260.1747965039, 41281.38077928834, 41293.38059929074, 41369.58630413687, 3186.6972637599392, 3236.6277878044093, 3351.8156501392414, 3809.4285857121654, 4099.938500922489, 29907.112075430054, 30519.847400763076, 30524.548846151858, 30784.0, 30835.074947751604, 2711.2815103997455, 2758.3931535062256, 2783.73554512323, 2845.374017716099, 2862.8997985070423, 483235.0, 483838.0, 485054.0, 485268.0, 485873.0, 63863.36136638667, 63967.99999999907, 64148.67925660435, 64259.0, 64311.35688643147, 68574.9713754294, 68604.0, 68704.96942545866, 68762.65618671874, 68777.96833047508, 16733.916330418513, 16989.575260618585, 16990.745138822927, 16997.0, 17034.574135646708, 11870.169088163746, 12164.756704865853, 12855.550055748192, 12907.483700652057, 12985.480580776852, 14324.713505729827, 17553.473395798148, 17877.553061173316, 18018.549536261697, 18150.727739083923, 6347.381417738447, 6449.838754031093, 6455.580387274812, 6498.54510184292, 6518.934810651927, 955.7085089047907, 1093.2074246171621, 1096.15595991088, 1111.2610114273916, 1145.4616330324734, 27806.582901256497, 28063.43873122567, 29932.0, 30470.54294185589, 30572.69427305743, 1061.4639606998417, 1073.313079629038, 1114.788190243851, 1125.5835340923911, 1148.8047032004579, 15418.614534636723, 15445.768313475306, 15501.379944802307, 15802.130882801557, 15898.966567173327, 646634.0, 656706.0, 661724.0, 662475.6876215588, 662940.0, 62770.68614656989, 62845.0, 62876.37123628797, 62923.68538157371, 62942.055869162, 1330.481112366178, 1368.322680273271, 1371.991586184154, 1482.0662982321069, 1495.446684726658, 30385.696143038287, 31213.90751323693, 31877.840610796797, 32169.874054407985, 32973.0, 58716.70641646764, 58947.70526147342, 59637.0, 59647.0, 59696.70151649214, 588594.0, 593018.0, 593993.0, 594182.0, 594578.0, 34942.825285873405, 35309.29381412409, 39891.60108398879, 40481.797591011855, 40535.783926482254, 40099.59651412186, 44826.77586612046, 47501.52498474971, 48059.27911081267, 49194.0, 33713.651453941566, 34419.827900860335, 36375.63624363722, 39184.0, 40294.79852600718, 29014.0, 36339.091522712144, 38155.790187002174, 38327.80836095801, 39017.219655607296, 38185.0, 38406.6159338403, 38885.8055709731, 39125.02187445336, 39188.0, 31482.425878706432, 32468.350632986734, 34003.319933600695, 34004.65995340114, 34024.97925062252, 22406.215782447536, 25067.0, 25309.987600495773, 25653.743462565137, 27208.591871121553], "value": 20799.45696523664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.292592851368, 384.2698872142928, 388.45422181834533, 400.45338113475117, 409.7930545074737, 19832.405027849152, 21576.352709418705, 21945.0, 21956.0, 22006.899655017267, 11600.129990250744, 12224.266544007365, 12232.755344893098, 20476.795232047676, 21417.9291035448, 5220.321358223435, 5329.680219186861, 5375.973120134394, 5389.51494365507, 5432.239486471897, 5239.21411788231, 5351.759170837302, 5375.354957405098, 5397.433269506704, 5412.377576578685, 5313.548348390392, 5402.78388864445, 5411.458854114577, 5412.404635490093, 5424.945750542503, 5249.842504724846, 5382.730863456812, 5384.165454354581, 5388.084025715638, 5413.810516631899, 5012.799488020475, 5230.555402790767, 5262.631615786897, 5265.973670131663, 5270.525652691276, 13158.289422375126, 13574.0, 19173.424797256106, 21695.37284703638, 24594.5081098377, 2073.8651987620906, 2121.1409379201295, 2318.6058370076958, 2386.7971222446113, 2686.5835795451735, 6437.935620643781, 6456.806295811086, 10085.596576137019, 10106.39361638304, 10314.226433017478, 1699.141933323676, 1699.4561740243128, 1705.2411676803827, 1720.053970316321, 1817.954551136219, 85440.0, 125587.0, 130358.99999999905, 130993.0, 134044.0, 9515.429074255568, 11480.0, 11749.823752643717, 11751.529938802352, 11882.940585297018, 15374.308156133, 15467.375925527882, 15508.5347439577, 15515.913886027869, 15519.612009699622, 7647.502912310682, 7652.120006199247, 8087.47431416956, 8276.503409795432, 8451.0, 8302.958485207535, 8308.376871734645, 8387.83224335522, 8502.872456913152, 8642.0, 4576.519465456146, 4676.0, 4688.859334219979, 4697.13103075932, 4736.455307639666, 1715.7426386041943, 1757.578181236494, 2000.0899590686204, 2003.0785838514155, 2007.6988451732357, 880.3133555826465, 977.3500622086233, 1032.4115254304975, 1041.401194313273, 1074.9301295415773, 19626.14971832522, 19957.700634490495, 20189.293374732108, 20378.38864834028, 20379.0, 756.9735059272899, 945.6170251048336, 962.1196605106389, 963.272729089533, 986.8667729856521, 8301.875471867806, 10426.165906727443, 10470.73823154412, 10482.213833962483, 10778.838317425245, 541687.0, 545741.271293641, 562317.0, 631808.0, 632949.0, 145512.0, 154415.0, 156508.21745891427, 158476.0, 159028.0, 1023.3092662452964, 1042.6611351310855, 1080.8432777247413, 1081.378207530673, 1082.6643740440331, 18118.637627247645, 18498.0, 18502.167402466654, 18662.34681786131, 18824.623507530046, 46934.122635094216, 54121.72939135279, 55266.0, 56835.71582142063, 57222.0, 73492.0, 74569.0, 76508.61745691235, 78454.2154578447, 82651.3469730614, 8242.793930151794, 8337.749867503984, 8343.541105239028, 8389.706360277361, 8553.187447192508, 9116.225120864536, 11812.114091443003, 11984.40077996114, 13006.674833128869, 13075.934620326838, 9756.634071230175, 9794.75513112178, 11099.833502497308, 11276.154288428237, 11277.0, 8445.746627601182, 10863.076638485501, 10889.564417423531, 10981.341119532854, 11256.662300131009, 10996.615118470816, 11012.284201526874, 11408.258463199865, 11580.42097895085, 11619.47712352963, 8098.473599216032, 8129.634166462405, 8287.41988060854, 8401.579921004048, 8572.871406928776, 10578.259521833397, 10771.138308935424, 10941.781164376827, 11175.38535380562, 11208.103351732005], "value": 9298.553272900323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.7222555488904, 1624.910629915355, 1638.565780068282, 1882.6611209982207, 2009.8392128629703, 7693.538387696735, 8127.431079824415, 8346.958265208681, 8354.91645083549, 8457.661693532267, 8109.513429194252, 8312.501249925008, 8319.960004999375, 8327.750167494969, 8336.499810011404, 1926.9594419013715, 2081.5845225246826, 2331.3006098170567, 2380.4048987753063, 2411.710594728635, 2430.80553555716, 2442.9022839086415, 2450.3261603059213, 2456.6437866509345, 2459.2622213336017, 2369.0286982337243, 2393.1384701507445, 2411.360989337827, 2412.191915708231, 2414.468816860288, 2080.865928069198, 2300.723913130418, 2348.2250857217164, 2382.1781485387514, 2389.545986262622, 2410.746871578494, 2423.575874222004, 2429.9028038878423, 2430.1373012580634, 2431.7081950166094, 8619.396642235046, 8695.69565065226, 8696.652133914698, 8705.216530512283, 8710.520921349324, 5112.207607820794, 5119.155339368999, 5317.122674758699, 5348.91976620351, 5360.437154098802, 3364.6971772540587, 3410.9829450852835, 3508.6315936826522, 3518.9472157917653, 3540.2211513467255, 851.6210286422545, 923.8244733500613, 991.8264303746888, 1004.9397036177853, 1036.5750042482546, 11487.597934072268, 11497.770044599061, 11512.309261444256, 11524.42377881111, 11532.481038353295, 4335.328024156261, 4437.112577484516, 4507.549245075466, 4519.299508576176, 4536.931946020813, 9754.0, 9857.75355616101, 9860.16188623975, 9870.210383169473, 9872.210223182128, 5159.406668233126, 5192.584593232532, 5196.350456192976, 5198.2202669599865, 5202.583793296527, 4376.8905777355185, 4559.680822342404, 4775.2598347256235, 4899.216125419915, 4903.485134060944, 3287.7862938908893, 3946.940795888064, 4034.1326614777377, 4061.268971585084, 4075.673946084306, 1099.2689861242345, 1115.8005144469728, 1321.4119716725966, 1339.8459177194748, 1345.4550906882728, 921.1295325916944, 928.2713070239829, 930.7673081729567, 943.5329511891657, 988.5650313861878, 5053.974730126326, 5856.267966504187, 5954.047352423678, 5975.0, 6147.0, 457.25924003115165, 457.58817064641426, 460.2337108713954, 471.91930479202927, 478.841982145891, 3958.0302825807285, 3979.1245925896255, 4066.552679205285, 4077.8368865245056, 4080.6123418275215, 9791.804163916824, 10549.419781912069, 10549.578016879392, 10573.418461984666, 10661.520231589597, 10502.264841461025, 10527.789444211074, 10590.523426445829, 10608.310459820086, 10611.893881061245, 8004.5597492138495, 8143.226393492609, 8242.216989386, 8248.587570621445, 8261.793455163548, 5802.071668533184, 5810.6513609183585, 5832.504237139721, 5861.589688721749, 5926.911096333644, 7742.8451430969935, 9723.124918757347, 9755.560999754885, 9782.9021709782, 9896.901030989886, 9676.806463870824, 9693.127618514367, 9903.900960990299, 10008.949955250177, 10086.899131008597, 6795.728170873307, 7026.156861176692, 7206.4234861209925, 7483.438742094474, 7572.129205141479, 7955.681772729026, 8195.688689809602, 8433.114522975, 8462.619182136696, 8463.534505602005, 7251.057362542836, 7370.520916140434, 7506.24937506245, 7605.353544948742, 7687.461877668509, 7213.458990575833, 7372.336489715952, 7388.224236455309, 7492.925070749441, 7531.962340188264, 6978.825529361807, 7001.089858318386, 7045.929540704732, 7057.682404291717, 7078.18600860887, 4821.228603423436, 4911.361523001987, 5020.196768517019, 5040.521150490697, 5215.13950198213, 4961.479044700254, 5037.0681423936685, 5056.974715126401, 5131.8717032073, 5320.414754376938], "value": 4455.757124299132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.70041359467723, 307.5755457468693, 319.24498560903464, 324.45815488134815, 327.4433463112709, 4703.5061318561575, 5319.537127289997, 5320.4679532046775, 5387.16498942664, 5404.0545403654705, 3956.4065390191486, 4573.931391029138, 4998.350214472113, 5291.656042357254, 5294.3587487878685, 3405.5990526678634, 4035.757854528723, 4040.474738284019, 4040.969273045216, 4082.4486695055984, 3377.020664007435, 3850.4801851750076, 4048.2510735514024, 4085.387191921206, 4093.7543747375103, 3767.9811600941957, 3810.580836108026, 3827.770333779982, 3957.94187022748, 4010.2981978153853, 3509.7543171977973, 3512.455569386749, 3993.1813978134414, 4004.659603933669, 4065.674746020326, 3495.196104895863, 3509.175343794206, 3533.0460775590445, 3547.740552103992, 3582.0328511302064, 4485.416895803557, 4661.930071048903, 4891.437484689271, 5054.545090941796, 5122.513361230677, 3542.8582856685703, 4274.316109422478, 4303.720258183208, 4517.390152329426, 4629.819396054018, 5566.415526369713, 5589.944100558983, 5595.300587426572, 5597.608167428281, 5613.270274864282, 3490.912727181815, 3745.7190710696536, 3882.437046628223, 3893.5717071122153, 4069.9186016279805, 4855.5236829217, 5852.736626851802, 5858.970705146447, 5872.29532456108, 5891.293044834604, 3931.3881308663313, 3950.2692001979726, 3969.2656858480696, 3972.801359931992, 3983.7052957788255, 3644.7972169184104, 3830.71269654777, 3993.5806740292433, 4022.3162062449455, 4050.432939388488, 4240.681948853787, 4452.977735111303, 4571.519990401027, 4594.632429405639, 4667.369905062841, 4829.468758436569, 4867.269909513601, 5356.268303165413, 5579.358373786985, 5632.098864181711, 1207.124834494984, 1266.069438962362, 1302.5180683147296, 1302.742853146718, 1314.309987256698, 794.4597673582019, 841.8484672758963, 851.6721062391024, 867.5488745852122, 892.8393088984294, 4245.919499445249, 4450.777461126931, 4476.776161191928, 4939.987302602958, 4941.283513890466, 4473.552644735565, 4663.813447462131, 5436.592255580768, 5490.945090549043, 5527.977324194956, 4128.896777580585, 4312.396264522969, 4753.215719406259, 4928.0, 4987.226979818134, 2557.7058638256467, 2621.9606705899428, 2893.074216250822, 2931.4137172565147, 2950.0971873142134, 5197.272381866542, 5250.737463126905, 5504.53211477029, 5746.482816546531, 5842.620229685057, 5383.838484845461, 5421.241026256327, 5678.750674851526, 5720.3421606515785, 5733.222700962631, 5190.844274671766, 5332.466753324714, 5696.601237913306, 5698.3162020556965, 5721.799737009184, 4999.550040496373, 5031.597472202288, 5548.611597188158, 5627.352854421631, 5635.393348257036, 4889.584385327287, 5148.76830542619, 5264.578833693372, 5490.203920431435, 5520.199571062254, 5183.0912759455005, 5423.8541645844525, 5561.888762224652, 5746.224259724883, 5847.649141051551, 3219.5814544109116, 3637.9818100909324, 3673.687736542424, 3673.779573225615, 3692.390755525308, 4593.0, 4953.281774142802, 5528.778848846001, 5574.749136288796, 5605.971970140124, 5311.256424100552, 5369.301990741179, 5415.702136382537, 5444.5916556259235, 5527.1258945349, 5105.617078719037, 5161.458046905021, 5513.842093160481, 5614.382417933943, 5639.351474580476, 4483.663725220556, 4971.65198436106, 5456.345238571397, 5482.506574408322, 5503.697296648563, 4370.519242883217, 5559.805406810742, 5565.821937649287, 5580.804671836466, 5592.86017849557, 4260.595243451867, 5157.561607263425, 5246.606504512253, 5440.483154100354, 5508.531774799187], "value": 3981.8771719694473, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.3779256195871, 665.9400653941148, 715.7423327602062, 726.5422783646304, 757.8446418484211, 13444.731105377887, 14509.201993890332, 14960.700785984274, 15041.999999999985, 15520.844791552081, 14214.076085054465, 14281.92859035706, 14290.428382864673, 14296.857031429683, 14305.427782888672, 709.4749885085025, 884.4428010353469, 900.6307413960278, 902.7878448564582, 915.771057235691, 871.3682580129387, 882.6292956958093, 886.1537231943479, 887.2591386192537, 888.1340692824509, 749.6251874062968, 939.976500587484, 942.726609283307, 942.9339946203769, 943.273679266964, 929.5677509957868, 945.8250223708636, 948.1040416806121, 960.3661583354952, 962.129273007929, 721.0374150509076, 722.7181399254295, 722.7325889420948, 723.1791916175126, 723.2514347650157, 14567.490137845125, 14577.927110364486, 14615.853841461556, 14627.561173164822, 14634.121952682874, 10755.0, 12148.514059437612, 12238.143329966906, 12319.199252048678, 12495.687607809878, 10484.633037843716, 10530.68407947755, 10838.295510791773, 10870.159226966785, 11123.666290011231, 3942.8225729842225, 3965.920681586352, 3976.7415118017525, 3978.423128646359, 3987.4616926715103, 66804.6659766703, 68839.27901802445, 69195.65402172957, 77136.61431692862, 77303.84044239341, 8981.955090224506, 9248.7687807804, 9283.303752218611, 9641.710748677551, 9687.806243875082, 20720.171193152106, 20954.580908382053, 20981.0, 20994.580108397746, 21014.89492552558, 7189.065421495172, 7268.236835132235, 7378.0, 7378.778636640909, 7500.699972001168, 4071.97964010184, 4115.814788334532, 4127.772972486482, 4139.379093136054, 4154.8338066477, 3437.759356845047, 3565.0017994961454, 3567.2687099144614, 3573.213892943548, 3577.0520811984843, 568.6076607141059, 576.6684156609966, 595.5943972225544, 601.5097695378253, 661.6030381770995, 506.50615649741206, 529.751017021999, 568.8321945026228, 590.9556783241275, 592.754007087054, 7075.186353569303, 8027.879581806277, 8068.9596552016865, 8123.350131989427, 8147.103818579952, 531.7421050790364, 540.6053580885897, 595.3034949109552, 613.1354789746437, 617.4350469320611, 3780.8676696315497, 3896.649301562816, 3944.4280579315837, 3976.840926362971, 3977.76133431995, 99615.5173017216, 99910.50044749875, 101086.98913010923, 102724.0, 104732.0, 60584.394156057875, 84027.0, 85402.0, 88465.55767221123, 88833.0, 79449.60275198588, 80614.0, 80751.0, 80789.59605201936, 81314.18685813184, 7370.0, 7489.438292128003, 7587.317141457295, 7595.734149304748, 7685.353073691713, 38736.80631596824, 45331.61365147458, 47753.522464776295, 48072.0, 48424.757876210395, 46923.53076469192, 47097.0, 47889.0, 64922.727704530116, 65107.04678859642, 9006.324525660471, 10860.348379097279, 10936.78126437451, 11582.131340149353, 12221.572244971383, 11819.40902954866, 12164.817527737268, 12240.204386714835, 12442.315672638091, 12465.750684986431, 11163.944180279372, 11223.494942727433, 11268.943655281671, 11562.30626162433, 11838.0, 11434.828477572677, 11589.594364197214, 11767.941160294486, 11959.940200298943, 12265.448054837732, 11100.167487438313, 11122.44387780624, 11235.831462527905, 11348.092152627605, 12080.818787718366, 9005.23455506263, 9015.078281494276, 9064.18422341966, 9280.264382518675, 9459.0, 8717.215450609221, 8892.714282871466, 9551.713448596553, 9767.462789546644, 9814.178249389784], "value": 6817.9156764859, "unit": "score"}], "name": "dromaeo_css", "value": 7140.898776777042, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 112.0, 113.0, 111.0, 112.0, 112.0, 113.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 149.0, 246.0, 118.0, 142.0, 137.0, 118.0, 171.0, 121.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 186.0, 182.0, 187.0, 188.0, 185.0, 186.0, 184.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 59.0, 156.0, 81.0, 91.0, 58.0, 59.0, 111.0, 56.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 134.0, 81.0, 81.0, 78.0, 134.0, 116.0, 78.0, 101.0], "value": 99.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 87.0, 90.0, 91.0, 92.0, 85.0, 88.0, 91.0, 87.0, 83.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 111.0, 101.0, 126.0, 93.0, 93.0, 94.0, 93.0, 93.0, 95.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 86.0, 83.0, 83.0, 82.0, 83.0, 85.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 57.0, 59.0, 59.0, 60.0, 123.0, 60.0, 59.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 67.0, 71.0, 72.0, 69.0, 65.0, 66.0, 69.0, 72.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 107.0, 109.0, 106.0, 86.0, 86.0, 82.0, 84.0, 94.0, 107.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 174.0, 151.0, 148.0, 146.0, 140.0, 142.0, 153.0, 253.0, 141.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 224.0, 181.0, 173.0, 168.0, 168.0, 170.0, 171.0, 176.0, 221.0], "value": 182.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 63.0, 78.0, 83.0, 86.0, 87.0, 69.0, 66.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1545.3999999999999, "alertThreshold": 2.0}]} 03:29:13 INFO - Return code: 0 03:29:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:29:13 ERROR - # TBPL SUCCESS # 03:29:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:13 INFO - 2016-05-10 03:29:13.093 screenresolution[1677:9698] starting screenresolution argv=screenresolution get 03:29:13 INFO - 2016-05-10 03:29:13.095 screenresolution[1677:9698] Display 0: 1600x1200x32@60 03:29:13 INFO - 2016-05-10 03:29:13.101 screenresolution[1678:9701] starting screenresolution argv=screenresolution list 03:29:13 INFO - Available Modes on Display 0 03:29:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:13 INFO - Intel Iris: 03:29:13 INFO - Chipset Model: Intel Iris 03:29:13 INFO - Type: GPU 03:29:13 INFO - Bus: Built-In 03:29:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:13 INFO - Vendor: Intel (0x8086) 03:29:13 INFO - Device ID: 0x0a2e 03:29:13 INFO - Revision ID: 0x0009 03:29:13 INFO - Displays: 03:29:13 INFO - Display: 03:29:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:13 INFO - Display Serial Number: 1600x1200 60 03:29:13 INFO - Main Display: Yes 03:29:13 INFO - Mirror: Off 03:29:13 INFO - Online: Yes 03:29:13 INFO - Rotation: Supported 03:29:13 INFO - Return code: 0 03:29:13 INFO - Running post-action listener: _resource_record_post_action 03:29:13 INFO - ##### 03:29:13 INFO - ##### Finished run-tests step (success) 03:29:13 INFO - ##### 03:29:13 INFO - Running post-run listener: _resource_record_post_run 03:29:14 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37692928; Write bytes: 698607104; Read time: 398; Write time: 1887 03:29:14 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389736960; Write bytes: 379831808; Read time: 29592; Write time: 729 03:29:14 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30625280; Write bytes: 297308160; Read time: 305; Write time: 1109 03:29:14 INFO - Running post-run listener: _upload_blobber_files 03:29:14 INFO - Blob upload gear active. 03:29:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29:14 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 03:29:14 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'] 03:29:14 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 03:29:14 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:29:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:16 INFO - (blobuploader) - INFO - Done attempting. 03:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:16 INFO - (blobuploader) - INFO - Done attempting. 03:29:16 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:16 INFO - Return code: 0 03:29:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/78b1ef35e4e3009e5ea3297e53dc02aeeea28e55d6f69e68efb30081ded397695ffe077c2b118a5959ff94be4aa6f6404b2960babb016fc5656f0d59315ac2cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/478edad95f12aee9d62493e37b65637d3a3d2e4406a8a394f3d85197b1f574d9c9b2b99aa6decad18bc567f5ad093cd7c5dba848f962febdfc6777e6d060e14f"} 03:29:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:16 INFO - Contents: 03:29:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/78b1ef35e4e3009e5ea3297e53dc02aeeea28e55d6f69e68efb30081ded397695ffe077c2b118a5959ff94be4aa6f6404b2960babb016fc5656f0d59315ac2cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/478edad95f12aee9d62493e37b65637d3a3d2e4406a8a394f3d85197b1f574d9c9b2b99aa6decad18bc567f5ad093cd7c5dba848f962febdfc6777e6d060e14f"} 03:29:16 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:16 INFO - Copying logs to upload dir... 03:29:16 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.388404 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-05-10 03:29:17.047244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 03:29:17.050571) ========= 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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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/78b1ef35e4e3009e5ea3297e53dc02aeeea28e55d6f69e68efb30081ded397695ffe077c2b118a5959ff94be4aa6f6404b2960babb016fc5656f0d59315ac2cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/478edad95f12aee9d62493e37b65637d3a3d2e4406a8a394f3d85197b1f574d9c9b2b99aa6decad18bc567f5ad093cd7c5dba848f962febdfc6777e6d060e14f"} build_url:https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/c5H2mfLcTk6nwJpcGooKBA/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/78b1ef35e4e3009e5ea3297e53dc02aeeea28e55d6f69e68efb30081ded397695ffe077c2b118a5959ff94be4aa6f6404b2960babb016fc5656f0d59315ac2cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/478edad95f12aee9d62493e37b65637d3a3d2e4406a8a394f3d85197b1f574d9c9b2b99aa6decad18bc567f5ad093cd7c5dba848f962febdfc6777e6d060e14f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 03:29:17.080048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:29:17.080587) ========= 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.X8t88Nb2yU/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.fT6P1WEhJd/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005272 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 03:29:17.130189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 03:29:17.130493) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 03:29:17.130792) ========= ========= Total master_lag: 0.15 =========